builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1504431782.76 results: success (0) revision: 59db725def8282e1d77e83f002d247c7d0f95237 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:02.762562) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:02.763283) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:02.763558) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:02.884874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:02.889938) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-03 02:43:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.6M=0s 2017-09-03 02:43:03 (69.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.162856 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.083729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.084047) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.083643 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.214899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 14 secs) (at 2017-09-03 02:43:03.215269) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 59db725def8282e1d77e83f002d247c7d0f95237 --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 59db725def8282e1d77e83f002d247c7d0f95237 --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=56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-03 02:43:03,388 truncating revision to first 12 chars 2017-09-03 02:43:03,389 Setting DEBUG logging. 2017-09-03 02:43:03,389 attempt 1/10 2017-09-03 02:43:03,389 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/59db725def82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-03 02:43:03,874 attempt 1/10 2017-09-03 02:43:04,438 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:04,439 sleeping for 9.00s (attempt 1/10) 2017-09-03 02:43:13,448 attempt 2/10 2017-09-03 02:43:13,987 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:13,988 sleeping for 13.50s (attempt 2/10) 2017-09-03 02:43:27,501 attempt 3/10 2017-09-03 02:43:27,998 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:27,998 sleeping for 19.25s (attempt 3/10) 2017-09-03 02:43:47,268 attempt 4/10 2017-09-03 02:43:47,805 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:47,806 sleeping for 28.88s (attempt 4/10) 2017-09-03 02:44:16,710 attempt 5/10 2017-09-03 02:44:17,019 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-03 02:44:17,315 unpacking tar archive at: mozilla-central-59db725def82/testing/mozharness/ program finished with exit code 0 elapsedTime=74.221029 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 14 secs) (at 2017-09-03 02:44:17.483638) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:17.483995) ========= script_repo_revision: 59db725def8282e1d77e83f002d247c7d0f95237 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:17.484417) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:17.484696) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:17.497909) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-09-03 02:44:17.498329) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:44:17 INFO - MultiFileLogger online at 20170903 02:44:17 in /builds/slave/test 02:44:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 02:44:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:44:17 INFO - {'append_to_log': False, 02:44:17 INFO - 'base_work_dir': '/builds/slave/test', 02:44:17 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:44:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:44:17 INFO - 'branch': 'Firefox-Non-PGO', 02:44:17 INFO - 'buildbot_json_path': 'buildprops.json', 02:44:17 INFO - 'code_coverage': False, 02:44:17 INFO - 'config_files': ('talos/linux_config.py',), 02:44:17 INFO - 'default_actions': ('clobber', 02:44:17 INFO - 'read-buildbot-config', 02:44:17 INFO - 'download-and-extract', 02:44:17 INFO - 'populate-webroot', 02:44:17 INFO - 'create-virtualenv', 02:44:17 INFO - 'install', 02:44:17 INFO - 'setup-mitmproxy', 02:44:17 INFO - 'run-tests'), 02:44:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:44:17 INFO - 'disable_ccov_upload': False, 02:44:17 INFO - 'disable_stylo': False, 02:44:17 INFO - 'download_minidump_stackwalk': True, 02:44:17 INFO - 'download_symbols': 'ondemand', 02:44:17 INFO - 'enable_stylo': False, 02:44:17 INFO - 'enable_webrender': False, 02:44:17 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:44:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:44:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:44:17 INFO - 'gecko_profile': False, 02:44:17 INFO - 'gecko_profile_interval': 0, 02:44:17 INFO - 'installer_path': 'installer.exe', 02:44:17 INFO - 'log_level': 'info', 02:44:17 INFO - 'log_name': 'talos', 02:44:17 INFO - 'log_to_console': True, 02:44:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:44:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:44:17 INFO - 'opt_config_files': (), 02:44:17 INFO - 'pip_index': False, 02:44:17 INFO - 'suite': 'dromaeojs-e10s', 02:44:17 INFO - 'system_bits': '32', 02:44:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:44:17 INFO - 'title': 'talos-linux64-ix-037', 02:44:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:44:17 INFO - 'use_talos_json': True, 02:44:17 INFO - 'verify': 'False', 02:44:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:44:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:44:17 INFO - 'work_dir': 'build'} 02:44:17 INFO - [mozharness: 2017-09-03 09:44:17.852448Z] Running clobber step. 02:44:17 INFO - Running pre-action listener: _resource_record_pre_action 02:44:17 INFO - Running main action method: clobber 02:44:17 INFO - rmtree: /builds/slave/test/build 02:44:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:44:19 INFO - Running post-action listener: _resource_record_post_action 02:44:19 INFO - [mozharness: 2017-09-03 09:44:19.945453Z] Finished clobber step (success) 02:44:19 INFO - [mozharness: 2017-09-03 09:44:19.945602Z] Running read-buildbot-config step. 02:44:19 INFO - Running pre-action listener: _resource_record_pre_action 02:44:19 INFO - Running main action method: read_buildbot_config 02:44:19 INFO - Using buildbot properties: 02:44:19 INFO - { 02:44:19 INFO - "project": "", 02:44:19 INFO - "product": "firefox", 02:44:19 INFO - "who": "archaeopteryx@coole-files.de", 02:44:19 INFO - "installer_path": "public/build/target.tar.bz2", 02:44:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:44:19 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:44:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 02:44:19 INFO - "stage_platform": "linux64", 02:44:19 INFO - "basedir": "/builds/slave/test", 02:44:19 INFO - "buildnumber": 806, 02:44:19 INFO - "platform": "ubuntu64_hw", 02:44:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:44:19 INFO - "slavebuilddir": "test", 02:44:19 INFO - "taskId": "aNPDHn53QoCsY2knkn4c8w", 02:44:19 INFO - "branch": "mozilla-central", 02:44:19 INFO - "script_repo_revision": "production", 02:44:19 INFO - "revision": "59db725def8282e1d77e83f002d247c7d0f95237", 02:44:19 INFO - "slavename": "talos-linux64-ix-037", 02:44:19 INFO - "repo_path": "mozilla-central" 02:44:19 INFO - } 02:44:19 INFO - Finding installer, test and symbols from parent task. 02:44:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aNPDHn53QoCsY2knkn4c8w'}, attempt #1 02:44:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aNPDHn53QoCsY2knkn4c8w'}, attempt #1 02:44:20 INFO - Task dependencies: JG8GD8vBTLSpKotpMpyhnw 02:44:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw'}, attempt #1 02:44:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:21 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json 02:44:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip 02:44:21 INFO - Running post-action listener: _resource_record_post_action 02:44:21 INFO - [mozharness: 2017-09-03 09:44:21.277405Z] Finished read-buildbot-config step (success) 02:44:21 INFO - [mozharness: 2017-09-03 09:44:21.277618Z] Running download-and-extract step. 02:44:21 INFO - Running pre-action listener: _resource_record_pre_action 02:44:21 INFO - Running main action method: download_and_extract 02:44:21 INFO - mkdir: /builds/slave/test/build/tests 02:44:21 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')]} 02:44:21 INFO - trying https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json 02:44:21 INFO - Downloading https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:44:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:44:21 INFO - Downloaded 989 bytes. 02:44:21 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:44:21 INFO - Using the following test package requirements: 02:44:21 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:44:21 INFO - u'common': [u'target.common.tests.zip'], 02:44:21 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:44:21 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:44:21 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:44:21 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:44:21 INFO - u'mozbase': [u'target.common.tests.zip'], 02:44:21 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:44:21 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:44:21 INFO - u'web-platform': [u'target.common.tests.zip', 02:44:21 INFO - u'target.web-platform.tests.tar.gz'], 02:44:21 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:44:21 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:44:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip 02:44:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:44:21 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip into memory 02:44:22 INFO - Content-Length response header: 55022487 02:44:22 INFO - Bytes received: 55022487 02:44:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:44:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip 02:44:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:44:33 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip into memory 02:44:34 INFO - Content-Length response header: 55022487 02:44:34 INFO - Bytes received: 55022487 02:44:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.talos.tests.zip 02:44:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:44:47 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.talos.tests.zip into memory 02:44:47 INFO - Content-Length response header: 14496650 02:44:47 INFO - Bytes received: 14496650 02:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:48 INFO - trying https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:48 INFO - Downloading https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:44:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:44:49 INFO - Downloaded 62661590 bytes. 02:44:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:49 INFO - mkdir: /builds/slave/test/properties 02:44:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:44:49 INFO - Writing to file /builds/slave/test/properties/build_url 02:44:49 INFO - Contents: 02:44:49 INFO - build_url:https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:49 INFO - Running post-action listener: _resource_record_post_action 02:44:49 INFO - Running post-action listener: find_tests_for_verification 02:44:49 INFO - Running post-action listener: set_extra_try_arguments 02:44:49 INFO - [mozharness: 2017-09-03 09:44:49.121372Z] Finished download-and-extract step (success) 02:44:49 INFO - [mozharness: 2017-09-03 09:44:49.121467Z] Running populate-webroot step. 02:44:49 INFO - Running pre-action listener: _resource_record_pre_action 02:44:49 INFO - Running main action method: populate_webroot 02:44:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:44:49 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:44:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:44:49 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:44:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:44:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:49 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:49 INFO - 'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:44:49 INFO - 'tests': ['damp', 'tps']}, 02:44:49 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:49 INFO - 'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['damp', 'tps']}, 02:44:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:44:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:44:49 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:44:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:44:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:49 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:49 INFO - 'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:49 INFO - 'other-e10s': {'tests': ['a11yr', 02:44:49 INFO - 'ts_paint', 02:44:49 INFO - 'tpaint', 02:44:49 INFO - 'sessionrestore', 02:44:49 INFO - 'sessionrestore_many_windows', 02:44:49 INFO - 'sessionrestore_no_auto_restore', 02:44:49 INFO - 'tabpaint']}, 02:44:49 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['a11yr', 02:44:49 INFO - 'ts_paint', 02:44:49 INFO - 'tpaint', 02:44:49 INFO - 'sessionrestore', 02:44:49 INFO - 'sessionrestore_many_windows', 02:44:49 INFO - 'sessionrestore_no_auto_restore', 02:44:49 INFO - 'tabpaint']}, 02:44:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:44:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:44:49 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['bloom_basic_singleton']}, 02:44:49 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['bloom_basic']}, 02:44:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:44:49 INFO - 'tsvgr_opacity', 02:44:49 INFO - 'tart', 02:44:49 INFO - 'tscrollx', 02:44:49 INFO - 'tsvg_static']}, 02:44:49 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['tsvgx', 02:44:49 INFO - 'tsvgr_opacity', 02:44:49 INFO - 'tart', 02:44:49 INFO - 'tscrollx', 02:44:49 INFO - 'tsvg_static']}, 02:44:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:44:49 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:49 INFO - 'talos_options': ['--enable-stylo'], 02:44:49 INFO - 'tests': ['tp5o']}, 02:44:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:49 INFO - 'talos_options': ['--mitmproxy', 02:44:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:49 INFO - '--firstNonBlankPaint'], 02:44:49 INFO - 'tests': ['tp6_google', 02:44:49 INFO - 'tp6_youtube', 02:44:49 INFO - 'tp6_amazon', 02:44:49 INFO - 'tp6_facebook']}, 02:44:49 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:49 INFO - 'talos_options': ['--enable-stylo', 02:44:49 INFO - '--mitmproxy', 02:44:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:49 INFO - '--firstNonBlankPaint'], 02:44:49 INFO - 'tests': ['tp6_google', 02:44:49 INFO - 'tp6_youtube', 02:44:49 INFO - 'tp6_amazon', 02:44:49 INFO - 'tp6_facebook']}, 02:44:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:49 INFO - 'talos_options': ['--enable-stylo', 02:44:49 INFO - '--stylo-threads=1', 02:44:49 INFO - '--mitmproxy', 02:44:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:49 INFO - '--firstNonBlankPaint'], 02:44:49 INFO - 'tests': ['tp6_google', 02:44:49 INFO - 'tp6_youtube', 02:44:49 INFO - 'tp6_amazon', 02:44:49 INFO - 'tp6_facebook']}, 02:44:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:44:49 INFO - 'talos_options': ['--xperf_path', 02:44:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:49 INFO - 'tests': ['tp5n']}, 02:44:49 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:49 INFO - 'talos_options': ['--enable-stylo', 02:44:49 INFO - '--xperf_path', 02:44:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:49 INFO - 'tests': ['tp5n']}}} 02:44:49 INFO - Running post-action listener: _resource_record_post_action 02:44:49 INFO - [mozharness: 2017-09-03 09:44:49.135592Z] Finished populate-webroot step (success) 02:44:49 INFO - [mozharness: 2017-09-03 09:44:49.135679Z] Running create-virtualenv step. 02:44:49 INFO - Running pre-action listener: _resource_record_pre_action 02:44:49 INFO - Running main action method: create_virtualenv 02:44:49 INFO - Creating virtualenv /builds/slave/test/build/venv 02:44:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:44:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:44:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:44:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:44:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:49 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:50 INFO - Installing pip...............done. 02:44:50 INFO - Return code: 0 02:44:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:44:50 INFO - Reading from file tmpfile_stdout 02:44:50 INFO - Output received: 02:44:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:44:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:44:50 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')]} 02:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:50 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')]} 02:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:50 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 0x11191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', '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 02:44:50 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 02:44:50 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 02:44:50 INFO - Using env: {'DISPLAY': ':0', 02:44:50 INFO - 'HOME': '/home/cltbld', 02:44:50 INFO - 'LANG': 'en_US.UTF-8', 02:44:50 INFO - 'LANGUAGE': 'en_US:en', 02:44:50 INFO - 'LOGNAME': 'cltbld', 02:44:50 INFO - 'MAIL': '/var/mail/cltbld', 02:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:50 INFO - 'MOZ_NO_REMOTE': '1', 02:44:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:50 INFO - 'NO_EM_RESTART': '1', 02:44:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:50 INFO - 'PWD': '/builds/slave/test', 02:44:50 INFO - 'SHELL': '/bin/bash', 02:44:50 INFO - 'SHLVL': '1', 02:44:50 INFO - 'TERM': 'linux', 02:44:50 INFO - 'TMOUT': '86400', 02:44:50 INFO - 'USER': 'cltbld', 02:44:50 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:50 INFO - '_': '/tools/buildbot/bin/python'} 02:44:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:44:51 INFO - Downloading/unpacking pip>=1.5 02:44:51 INFO - Running setup.py egg_info for package pip 02:44:51 INFO - warning: no files found matching 'pip/cacert.pem' 02:44:51 INFO - warning: no files found matching '*.html' under directory 'docs' 02:44:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:44:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:44:51 INFO - Installing collected packages: pip 02:44:51 INFO - Found existing installation: pip 1.1 02:44:51 INFO - Uninstalling pip: 02:44:51 INFO - Successfully uninstalled pip 02:44:51 INFO - Running setup.py install for pip 02:44:51 INFO - warning: no files found matching 'pip/cacert.pem' 02:44:51 INFO - warning: no files found matching '*.html' under directory 'docs' 02:44:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:44:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:44:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Successfully installed pip 02:44:51 INFO - Cleaning up... 02:44:51 INFO - Return code: 0 02:44:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', '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 02:44:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:44:51 INFO - Using env: {'DISPLAY': ':0', 02:44:51 INFO - 'HOME': '/home/cltbld', 02:44:51 INFO - 'LANG': 'en_US.UTF-8', 02:44:51 INFO - 'LANGUAGE': 'en_US:en', 02:44:51 INFO - 'LOGNAME': 'cltbld', 02:44:51 INFO - 'MAIL': '/var/mail/cltbld', 02:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:51 INFO - 'MOZ_NO_REMOTE': '1', 02:44:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:51 INFO - 'NO_EM_RESTART': '1', 02:44:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:51 INFO - 'PWD': '/builds/slave/test', 02:44:51 INFO - 'SHELL': '/bin/bash', 02:44:51 INFO - 'SHLVL': '1', 02:44:51 INFO - 'TERM': 'linux', 02:44:51 INFO - 'TMOUT': '86400', 02:44:51 INFO - 'USER': 'cltbld', 02:44:51 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:51 INFO - '_': '/tools/buildbot/bin/python'} 02:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:51 INFO - Downloading/unpacking psutil>=3.1.1 02:44:51 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 02:44:51 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 02:44:51 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 02:44:51 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 02:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:44:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:53 INFO - Installing collected packages: psutil 02:44:53 INFO - Running setup.py install for psutil 02:44:53 INFO - building 'psutil._psutil_linux' extension 02:44:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:44:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:44:54 INFO - building 'psutil._psutil_posix' extension 02:44:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 02:44:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 02:44:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:54 INFO - Successfully installed psutil 02:44:54 INFO - Cleaning up... 02:44:54 INFO - Return code: 0 02:44:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', '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 02:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:44:54 INFO - Using env: {'DISPLAY': ':0', 02:44:54 INFO - 'HOME': '/home/cltbld', 02:44:54 INFO - 'LANG': 'en_US.UTF-8', 02:44:54 INFO - 'LANGUAGE': 'en_US:en', 02:44:54 INFO - 'LOGNAME': 'cltbld', 02:44:54 INFO - 'MAIL': '/var/mail/cltbld', 02:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:54 INFO - 'MOZ_NO_REMOTE': '1', 02:44:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:54 INFO - 'NO_EM_RESTART': '1', 02:44:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:54 INFO - 'PWD': '/builds/slave/test', 02:44:54 INFO - 'SHELL': '/bin/bash', 02:44:54 INFO - 'SHLVL': '1', 02:44:54 INFO - 'TERM': 'linux', 02:44:54 INFO - 'TMOUT': '86400', 02:44:54 INFO - 'USER': 'cltbld', 02:44:54 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:54 INFO - '_': '/tools/buildbot/bin/python'} 02:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:44:55 INFO - Installing collected packages: mozsystemmonitor 02:44:55 INFO - Running setup.py install for mozsystemmonitor 02:44:56 INFO - Successfully installed mozsystemmonitor 02:44:56 INFO - Cleaning up... 02:44:56 INFO - Return code: 0 02:44:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', '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 02:44:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:44:56 INFO - Using env: {'DISPLAY': ':0', 02:44:56 INFO - 'HOME': '/home/cltbld', 02:44:56 INFO - 'LANG': 'en_US.UTF-8', 02:44:56 INFO - 'LANGUAGE': 'en_US:en', 02:44:56 INFO - 'LOGNAME': 'cltbld', 02:44:56 INFO - 'MAIL': '/var/mail/cltbld', 02:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:56 INFO - 'MOZ_NO_REMOTE': '1', 02:44:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:56 INFO - 'NO_EM_RESTART': '1', 02:44:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:56 INFO - 'PWD': '/builds/slave/test', 02:44:56 INFO - 'SHELL': '/bin/bash', 02:44:56 INFO - 'SHLVL': '1', 02:44:56 INFO - 'TERM': 'linux', 02:44:56 INFO - 'TMOUT': '86400', 02:44:56 INFO - 'USER': 'cltbld', 02:44:56 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:56 INFO - '_': '/tools/buildbot/bin/python'} 02:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:56 INFO - Downloading/unpacking jsonschema==2.5.1 02:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:44:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:57 INFO - Downloading functools32-3.2.3-2.tar.gz 02:44:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:44:57 INFO - warning: no files found matching '*.txt' 02:44:57 INFO - no previously-included directories found matching 'build' 02:44:57 INFO - no previously-included directories found matching 'dist' 02:44:57 INFO - no previously-included directories found matching '.git*' 02:44:57 INFO - Installing collected packages: jsonschema, functools32 02:44:57 INFO - Running setup.py install for functools32 02:44:57 INFO - warning: no files found matching '*.txt' 02:44:57 INFO - no previously-included directories found matching 'build' 02:44:57 INFO - no previously-included directories found matching 'dist' 02:44:57 INFO - no previously-included directories found matching '.git*' 02:44:57 INFO - Successfully installed jsonschema functools32 02:44:57 INFO - Cleaning up... 02:44:57 INFO - Return code: 0 02:44:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', '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 02:44:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:44:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:44:57 INFO - Using env: {'DISPLAY': ':0', 02:44:57 INFO - 'HOME': '/home/cltbld', 02:44:57 INFO - 'LANG': 'en_US.UTF-8', 02:44:57 INFO - 'LANGUAGE': 'en_US:en', 02:44:57 INFO - 'LOGNAME': 'cltbld', 02:44:57 INFO - 'MAIL': '/var/mail/cltbld', 02:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:57 INFO - 'MOZ_NO_REMOTE': '1', 02:44:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:57 INFO - 'NO_EM_RESTART': '1', 02:44:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:57 INFO - 'PWD': '/builds/slave/test', 02:44:57 INFO - 'SHELL': '/bin/bash', 02:44:57 INFO - 'SHLVL': '1', 02:44:57 INFO - 'TERM': 'linux', 02:44:57 INFO - 'TMOUT': '86400', 02:44:57 INFO - 'USER': 'cltbld', 02:44:57 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:57 INFO - '_': '/tools/buildbot/bin/python'} 02:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:44:57 INFO - Cleaning up... 02:44:57 INFO - Return code: 0 02:44:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', '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 02:44:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:44:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:44:57 INFO - Using env: {'DISPLAY': ':0', 02:44:57 INFO - 'HOME': '/home/cltbld', 02:44:57 INFO - 'LANG': 'en_US.UTF-8', 02:44:57 INFO - 'LANGUAGE': 'en_US:en', 02:44:57 INFO - 'LOGNAME': 'cltbld', 02:44:57 INFO - 'MAIL': '/var/mail/cltbld', 02:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:57 INFO - 'MOZ_NO_REMOTE': '1', 02:44:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:57 INFO - 'NO_EM_RESTART': '1', 02:44:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:57 INFO - 'PWD': '/builds/slave/test', 02:44:57 INFO - 'SHELL': '/bin/bash', 02:44:57 INFO - 'SHLVL': '1', 02:44:57 INFO - 'TERM': 'linux', 02:44:57 INFO - 'TMOUT': '86400', 02:44:57 INFO - 'USER': 'cltbld', 02:44:57 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:57 INFO - '_': '/tools/buildbot/bin/python'} 02:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:58 INFO - Downloading/unpacking blobuploader==1.2.4 02:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:59 INFO - Downloading blobuploader-1.2.4.tar.gz 02:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:44:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:44:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:59 INFO - Downloading docopt-0.6.1.tar.gz 02:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:44:59 INFO - Installing collected packages: blobuploader, requests, docopt 02:44:59 INFO - Running setup.py install for blobuploader 02:44:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:44:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:44:59 INFO - Running setup.py install for requests 02:45:00 INFO - Running setup.py install for docopt 02:45:00 INFO - Successfully installed blobuploader requests docopt 02:45:00 INFO - Cleaning up... 02:45:00 INFO - Return code: 0 02:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45: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')]} 02:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45: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')]} 02:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', '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 02:45:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:45:00 INFO - Using env: {'DISPLAY': ':0', 02:45:00 INFO - 'HOME': '/home/cltbld', 02:45:00 INFO - 'LANG': 'en_US.UTF-8', 02:45:00 INFO - 'LANGUAGE': 'en_US:en', 02:45:00 INFO - 'LOGNAME': 'cltbld', 02:45:00 INFO - 'MAIL': '/var/mail/cltbld', 02:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:00 INFO - 'MOZ_NO_REMOTE': '1', 02:45:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:00 INFO - 'NO_EM_RESTART': '1', 02:45:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:00 INFO - 'PWD': '/builds/slave/test', 02:45:00 INFO - 'SHELL': '/bin/bash', 02:45:00 INFO - 'SHLVL': '1', 02:45:00 INFO - 'TERM': 'linux', 02:45:00 INFO - 'TMOUT': '86400', 02:45:00 INFO - 'USER': 'cltbld', 02:45:00 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:00 INFO - '_': '/tools/buildbot/bin/python'} 02:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:45:00 INFO - Running setup.py (path:/tmp/pip-Y736LU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:45:00 INFO - Running setup.py (path:/tmp/pip-8HoRbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:45:00 INFO - Running setup.py (path:/tmp/pip-lNIP6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:45:00 INFO - Running setup.py (path:/tmp/pip-qkW9Hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:45:00 INFO - Running setup.py (path:/tmp/pip-eStLIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:45:01 INFO - Running setup.py (path:/tmp/pip-UBcIae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:45:01 INFO - Running setup.py (path:/tmp/pip-XTMhil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:45:01 INFO - Running setup.py (path:/tmp/pip-FSnhEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:45:01 INFO - Running setup.py (path:/tmp/pip-oogYsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:45:01 INFO - Running setup.py (path:/tmp/pip-wqTdTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:45:01 INFO - Running setup.py (path:/tmp/pip-3D4CG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:45:01 INFO - Running setup.py (path:/tmp/pip-jDDIet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:45:01 INFO - Running setup.py (path:/tmp/pip-YBgvoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:45:01 INFO - Running setup.py (path:/tmp/pip-EZfWB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:01 INFO - Running setup.py (path:/tmp/pip-4MKle3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:45:01 INFO - Running setup.py (path:/tmp/pip-1NKzTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:45:01 INFO - Running setup.py (path:/tmp/pip-158X_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:45:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:45:01 INFO - Running setup.py install for manifestparser 02:45:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:45:01 INFO - Running setup.py install for mozcrash 02:45:02 INFO - Running setup.py install for mozdebug 02:45:02 INFO - Running setup.py install for mozdevice 02:45:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:45:02 INFO - Running setup.py install for mozfile 02:45:02 INFO - Running setup.py install for mozhttpd 02:45:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:45:02 INFO - Running setup.py install for mozinfo 02:45:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:45:02 INFO - Running setup.py install for mozInstall 02:45:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:45:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:45:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:45:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:45:02 INFO - Running setup.py install for mozleak 02:45:02 INFO - Running setup.py install for mozlog 02:45:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:45:02 INFO - Running setup.py install for moznetwork 02:45:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:45:02 INFO - Running setup.py install for mozprocess 02:45:03 INFO - Running setup.py install for mozprofile 02:45:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:45:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:45:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:45:03 INFO - Running setup.py install for mozrunner 02:45:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:45:03 INFO - Running setup.py install for mozscreenshot 02:45:03 INFO - Running setup.py install for moztest 02:45:03 INFO - Running setup.py install for mozversion 02:45:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:45:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:45:03 INFO - Cleaning up... 02:45:03 INFO - Return code: 0 02:45:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', '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 02:45:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:45:03 INFO - Using env: {'DISPLAY': ':0', 02:45:03 INFO - 'HOME': '/home/cltbld', 02:45:03 INFO - 'LANG': 'en_US.UTF-8', 02:45:03 INFO - 'LANGUAGE': 'en_US:en', 02:45:03 INFO - 'LOGNAME': 'cltbld', 02:45:03 INFO - 'MAIL': '/var/mail/cltbld', 02:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:03 INFO - 'MOZ_NO_REMOTE': '1', 02:45:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:03 INFO - 'NO_EM_RESTART': '1', 02:45:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:03 INFO - 'PWD': '/builds/slave/test', 02:45:03 INFO - 'SHELL': '/bin/bash', 02:45:03 INFO - 'SHLVL': '1', 02:45:03 INFO - 'TERM': 'linux', 02:45:03 INFO - 'TMOUT': '86400', 02:45:03 INFO - 'USER': 'cltbld', 02:45:03 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:03 INFO - '_': '/tools/buildbot/bin/python'} 02:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:45:03 INFO - Running setup.py (path:/tmp/pip-hiOX8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:45:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:45:03 INFO - Running setup.py (path:/tmp/pip-nY_gO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:45:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:45:03 INFO - Running setup.py (path:/tmp/pip-JwqDyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:45:04 INFO - Running setup.py (path:/tmp/pip-pnOToP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:45:04 INFO - Running setup.py (path:/tmp/pip-uyXgP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:45:04 INFO - Running setup.py (path:/tmp/pip-d8fkUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:45:04 INFO - Running setup.py (path:/tmp/pip-HUbfz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:45:04 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)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:45:04 INFO - Running setup.py (path:/tmp/pip-GydEky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:45:04 INFO - Running setup.py (path:/tmp/pip-KuDIrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:45:04 INFO - Running setup.py (path:/tmp/pip-RlJxlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:45:04 INFO - Running setup.py (path:/tmp/pip-ccjjTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:45:04 INFO - Running setup.py (path:/tmp/pip-WxgjWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:45:04 INFO - Running setup.py (path:/tmp/pip-xcQu9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:45:04 INFO - Running setup.py (path:/tmp/pip-SEowri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:04 INFO - Running setup.py (path:/tmp/pip-m8JZb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:45:04 INFO - Running setup.py (path:/tmp/pip-dSnSqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:45:04 INFO - Running setup.py (path:/tmp/pip-GkePHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:45:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:45: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 02:45: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 02:45: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 02:45: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 02:45:06 INFO - Downloading blessings-1.6.tar.gz 02:45:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:45:06 INFO - Installing collected packages: blessings 02:45:06 INFO - Running setup.py install for blessings 02:45:06 INFO - Successfully installed blessings 02:45:06 INFO - Cleaning up... 02:45:06 INFO - Return code: 0 02:45:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:45:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:45:06 INFO - Reading from file tmpfile_stdout 02:45:06 INFO - Current package versions: 02:45:06 INFO - argparse == 1.2.1 02:45:06 INFO - blessings == 1.6 02:45:06 INFO - blobuploader == 1.2.4 02:45:06 INFO - docopt == 0.6.1 02:45:06 INFO - functools32 == 3.2.3-2 02:45:06 INFO - jsonschema == 2.5.1 02:45:06 INFO - manifestparser == 1.1 02:45:06 INFO - mozInstall == 1.13 02:45:06 INFO - mozcrash == 1.0 02:45:06 INFO - mozdebug == 0.1 02:45:06 INFO - mozdevice == 0.51 02:45:06 INFO - mozfile == 1.2 02:45:06 INFO - mozhttpd == 0.7 02:45:06 INFO - mozinfo == 0.10 02:45:06 INFO - mozleak == 0.1 02:45:06 INFO - mozlog == 3.5 02:45:06 INFO - moznetwork == 0.27 02:45:06 INFO - mozprocess == 0.25 02:45:06 INFO - mozprofile == 0.28 02:45:06 INFO - mozrunner == 6.13 02:45:06 INFO - mozscreenshot == 0.1 02:45:06 INFO - mozsystemmonitor == 0.3 02:45:06 INFO - moztest == 0.8 02:45:06 INFO - mozversion == 1.4 02:45:06 INFO - psutil == 3.1.1 02:45:06 INFO - requests == 1.2.3 02:45:06 INFO - wsgiref == 0.1.2 02:45:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', '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 02:45:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:45:06 INFO - Using env: {'DISPLAY': ':0', 02:45:06 INFO - 'HOME': '/home/cltbld', 02:45:06 INFO - 'LANG': 'en_US.UTF-8', 02:45:06 INFO - 'LANGUAGE': 'en_US:en', 02:45:06 INFO - 'LOGNAME': 'cltbld', 02:45:06 INFO - 'MAIL': '/var/mail/cltbld', 02:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:06 INFO - 'MOZ_NO_REMOTE': '1', 02:45:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:06 INFO - 'NO_EM_RESTART': '1', 02:45:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:06 INFO - 'PWD': '/builds/slave/test', 02:45:06 INFO - 'SHELL': '/bin/bash', 02:45:06 INFO - 'SHLVL': '1', 02:45:06 INFO - 'TERM': 'linux', 02:45:06 INFO - 'TMOUT': '86400', 02:45:06 INFO - 'USER': 'cltbld', 02:45:06 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:06 INFO - '_': '/tools/buildbot/bin/python'} 02:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:45:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:45: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 02:45: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 02:45: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 02:45: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 02:45:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:45:08 INFO - Installing collected packages: simplejson 02:45:08 INFO - Running setup.py install for simplejson 02:45:08 INFO - building 'simplejson._speedups' extension 02:45:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 02:45:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 02:45:09 INFO - Successfully installed simplejson 02:45:09 INFO - Cleaning up... 02:45:09 INFO - Return code: 0 02:45:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', '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 02:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:45:09 INFO - Using env: {'DISPLAY': ':0', 02:45:09 INFO - 'HOME': '/home/cltbld', 02:45:09 INFO - 'LANG': 'en_US.UTF-8', 02:45:09 INFO - 'LANGUAGE': 'en_US:en', 02:45:09 INFO - 'LOGNAME': 'cltbld', 02:45:09 INFO - 'MAIL': '/var/mail/cltbld', 02:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:09 INFO - 'MOZ_NO_REMOTE': '1', 02:45:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:09 INFO - 'NO_EM_RESTART': '1', 02:45:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:09 INFO - 'PWD': '/builds/slave/test', 02:45:09 INFO - 'SHELL': '/bin/bash', 02:45:09 INFO - 'SHLVL': '1', 02:45:09 INFO - 'TERM': 'linux', 02:45:09 INFO - 'TMOUT': '86400', 02:45:09 INFO - 'USER': 'cltbld', 02:45:09 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:09 INFO - '_': '/tools/buildbot/bin/python'} 02:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:45:09 INFO - Cleaning up... 02:45:09 INFO - Return code: 0 02:45:09 INFO - Running post-action listener: _resource_record_post_action 02:45:09 INFO - Running post-action listener: _start_resource_monitoring 02:45:09 INFO - Starting resource monitoring. 02:45:09 INFO - [mozharness: 2017-09-03 09:45:09.308353Z] Finished create-virtualenv step (success) 02:45:09 INFO - [mozharness: 2017-09-03 09:45:09.309373Z] Running install step. 02:45:09 INFO - Running pre-action listener: _resource_record_pre_action 02:45:09 INFO - Running main action method: install 02:45:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:45:09 INFO - Reading from file tmpfile_stdout 02:45:09 INFO - Detecting whether we're running mozinstall >=1.0... 02:45:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:45:09 INFO - Reading from file tmpfile_stdout 02:45:09 INFO - Output received: 02:45:09 INFO - Usage: mozinstall [options] installer 02:45:09 INFO - Options: 02:45:09 INFO - -h, --help show this help message and exit 02:45:09 INFO - -d DEST, --destination=DEST 02:45:09 INFO - Directory to install application into. [default: 02:45:09 INFO - "/builds/slave/test"] 02:45:09 INFO - --app=APP Application being installed. [default: firefox] 02:45:09 INFO - mkdir: /builds/slave/test/build/application 02:45:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:45:26 INFO - Reading from file tmpfile_stdout 02:45:26 INFO - Output received: 02:45:26 INFO - /builds/slave/test/build/application/firefox/firefox 02:45:26 INFO - Running post-action listener: _resource_record_post_action 02:45:26 INFO - [mozharness: 2017-09-03 09:45:26.115324Z] Finished install step (success) 02:45:26 INFO - [mozharness: 2017-09-03 09:45:26.115571Z] Running setup-mitmproxy step. 02:45:26 INFO - Running pre-action listener: _resource_record_pre_action 02:45:26 INFO - Running main action method: setup_mitmproxy 02:45:26 INFO - Skipping: mitmproxy is not required 02:45:26 INFO - Running post-action listener: _resource_record_post_action 02:45:26 INFO - [mozharness: 2017-09-03 09:45:26.116678Z] Finished setup-mitmproxy step (success) 02:45:26 INFO - [mozharness: 2017-09-03 09:45:26.116897Z] Running run-tests step. 02:45:26 INFO - Running pre-action listener: _resource_record_pre_action 02:45:26 INFO - Running pre-action listener: _set_gcov_prefix 02:45:26 INFO - Running main action method: run_tests 02:45:26 WARNING - Try message not found. 02:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:26 INFO - Python 2.7.3 02:45:26 INFO - Return code: 0 02:45:26 INFO - grabbing minidump binary from tooltool 02:45:26 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')]} 02:45:26 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 0x1489090>, '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 0x1489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1483f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:26 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 02:45:26 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 02:45:26 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 02:45:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:26 INFO - Return code: 0 02:45:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:45:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:26 INFO - ENV: RUST_BACKTRACE is now full 02:45:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:45:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 02:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 02:45:26 INFO - Using env: {'DISPLAY': ':0', 02:45:26 INFO - 'HOME': '/home/cltbld', 02:45:26 INFO - 'LANG': 'en_US.UTF-8', 02:45:26 INFO - 'LANGUAGE': 'en_US:en', 02:45:26 INFO - 'LOGNAME': 'cltbld', 02:45:26 INFO - 'MAIL': '/var/mail/cltbld', 02:45:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:26 INFO - 'MOZ_NO_REMOTE': '1', 02:45:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:26 INFO - 'NO_EM_RESTART': '1', 02:45:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:26 INFO - 'PWD': '/builds/slave/test', 02:45:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:26 INFO - 'RUST_BACKTRACE': 'full', 02:45:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:26 INFO - 'SHELL': '/bin/bash', 02:45:26 INFO - 'SHLVL': '1', 02:45:26 INFO - 'TERM': 'linux', 02:45:26 INFO - 'TMOUT': '86400', 02:45:26 INFO - 'USER': 'cltbld', 02:45:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:26 INFO - '_': '/tools/buildbot/bin/python'} 02:45:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 02:45:26 INFO - mozversion application_buildid: 20170903085316 02:45:26 INFO - mozversion application_changeset: 59db725def8282e1d77e83f002d247c7d0f95237 02:45:26 INFO - mozversion application_display_name: Nightly 02:45:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:26 INFO - mozversion application_name: Firefox 02:45:26 INFO - mozversion application_remotingname: firefox 02:45:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:45:26 INFO - mozversion application_vendor: Mozilla 02:45:26 INFO - mozversion application_version: 57.0a1 02:45:26 INFO - mozversion platform_buildid: 20170903085316 02:45:26 INFO - mozversion platform_changeset: 59db725def8282e1d77e83f002d247c7d0f95237 02:45:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:45:26 INFO - mozversion platform_version: 57.0a1 02:45:26 INFO - using testdate: 1504431926 02:45:26 INFO - actual date: 1504431926 02:45:26 INFO - starting webserver on 'localhost:38445' 02:45:26 INFO - SUITE-START | Running 2 tests 02:45:26 INFO - TEST-START | dromaeo_css 02:45:26 INFO - Initialising browser for dromaeo_css test... 02:45:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38445/getInfo.html -profile /tmp/tmpQRKbPW/profile 02:45:26 INFO - TEST-INFO | started process 3631 (/builds/slave/test/build/application/firefox/firefox http://localhost:38445/getInfo.html) 02:45:34 INFO - TEST-INFO | 3631: exit 0 02:45:35 INFO - Browser initialized. 02:45:35 INFO - Running cycle 1/1 for dromaeo_css test... 02:45:35 INFO - Using env: {'DISPLAY': ':0', 02:45:35 INFO - 'HOME': '/home/cltbld', 02:45:35 INFO - 'JSGC_DISABLE_POISONING': '1', 02:45:35 INFO - 'LANG': 'en_US.UTF-8', 02:45:35 INFO - 'LANGUAGE': 'en_US:en', 02:45:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:45:35 INFO - 'LOGNAME': 'cltbld', 02:45:35 INFO - 'MAIL': '/var/mail/cltbld', 02:45:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:35 INFO - 'MOZ_CRASHREPORTER': '1', 02:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:45:35 INFO - 'MOZ_NO_REMOTE': '1', 02:45:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:35 INFO - 'NO_EM_RESTART': '1', 02:45:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:35 INFO - 'PWD': '/builds/slave/test', 02:45:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:35 INFO - 'RUST_BACKTRACE': 'full', 02:45:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:35 INFO - 'SHELL': '/bin/bash', 02:45:35 INFO - 'SHLVL': '1', 02:45:35 INFO - 'TERM': 'linux', 02:45:35 INFO - 'TMOUT': '86400', 02:45:35 INFO - 'USER': 'cltbld', 02:45:35 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 02:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:35 INFO - '_': '/tools/buildbot/bin/python'} 02:45:35 INFO - TEST-INFO | started process 3818 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQRKbPW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:36 INFO - PID 3818 | 02:45:36 INFO - PID 3818 | (/builds/slave/test/build/application/firefox/firefox:3870): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:45:36 INFO - PID 3818 | 02:45:46 INFO - PID 3818 | RSS: Main: 168181760 02:45:46 INFO - PID 3818 | 02:48:44 INFO - PID 3818 | Cycle 1(1): loaded http://localhost:38445/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38445/tests/dromaeo/cssquery-ext.html) 02:48:46 INFO - PID 3818 | RSS: Main: 171126784 02:48:46 INFO - PID 3818 | 02:51:48 INFO - PID 3818 | Cycle 1(1): loaded http://localhost:38445/tests/dromaeo/cssquery-ext.html (next: http://localhost:38445/tests/dromaeo/cssquery-jquery.html) 02:51:49 INFO - PID 3818 | RSS: Main: 168108032 02:51:49 INFO - PID 3818 | 02:54:50 INFO - PID 3818 | Cycle 1(1): loaded http://localhost:38445/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38445/tests/dromaeo/cssquery-mootools.html) 02:54:51 INFO - PID 3818 | RSS: Main: 169824256 02:54:51 INFO - PID 3818 | 02:57:53 INFO - PID 3818 | Cycle 1(1): loaded http://localhost:38445/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38445/tests/dromaeo/cssquery-prototype.html) 02:57:53 INFO - PID 3818 | RSS: Main: 167845888 02:57:53 INFO - PID 3818 | 03:00:57 INFO - PID 3818 | Cycle 1(1): loaded http://localhost:38445/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38445/tests/dromaeo/cssquery-yui.html) 03:00:57 INFO - PID 3818 | RSS: Main: 171958272 03:00:57 INFO - PID 3818 | 03:03:59 INFO - PID 3818 | Cycle 1(1): loaded http://localhost:38445/tests/dromaeo/cssquery-yui.html (next: http://localhost:38445/tests/dromaeo/cssquery-dojo.html) 03:03:59 INFO - PID 3818 | RSS: Main: 172056576 03:03:59 INFO - PID 3818 | 03:03:59 INFO - PID 3818 | __start_tp_report 03:03:59 INFO - PID 3818 | _x_x_mozilla_page_load 03:03:59 INFO - PID 3818 | _x_x_mozilla_page_load_details 03:03:59 INFO - PID 3818 | |i|pagename|runs| 03:03:59 INFO - PID 3818 | |0;dojo.html;3340.4989251612255;3356.1106306828697;3360.747943904207;3381.8647254109837;3392.3554524640317;6361.618302901829;6446.967765161174;6467.191601049869;6483.319251478598;6486.6432346221;6325.7153428095735;6385.297617262097;6413;6577.342265773432;6611.735530578771;5912.586118971673;5967.880642387149;6017.969910150443;6041.728122234488;6096.116063170837;1050.1421163401244;5781.335146458148;5850.210221620069;5926.3184733755525;5930.911036334458;5832.504237139849;5844;5926.259217597801;5945.375735547748;5956.404359564074;5839.240898683186;5854.443827836349;5929.673867937262;5936.406359364029;5957.225560677128;5820.912686309709;5832.912506312408;5892.263467066617;5900.970495147497;5909.290885093773;6302.9054564181115;6371.936280637227;6440.5491615586925;6458.257300410466;6466.418022377961;6786;6809.965950170267;6844.041834143224;6860.725570977155;6866.416354609864;6929.3070692930805;6936.375726184617;6946.2011868635245;7012.263712310185;7021.894671579879;6454.322296158883;6481.189851268591;6548.27968923418;6553.770618028346;6554;6993.160820701549;7073.328033836778;7078.97354883539;7089.14930208368;7091.397231235403;6732.932670673231;6744.494162937799;6745.730170793114;6752.932470675329;6763.222229443579;6843.623600702009;6874.2782007889455;6878.65606719662;6879.140107486564;6881.483888708367;6785;6813.182418109858;6853.897191542131;6888.655567221619;6923.9653801730665;6678.866422671519;6748.223954245226;6838.760643377458;6849.280825513349;6938.722451101899;5374.516293533601;5378.2739330189925;5435.510804027656;5437.864053398696;5439.156930675752;4713.151632706077;4723.574878260904;4738.715677059387;4790.449098353664;4798.712077275374;6015.157877897098;6093.939060609426;6099.75600975956;6107.48086412651;6117.571769976148;6860.279670634612;6881.311868813179;6959.826004349932;6984;7006.159260888624;5913.733881975321;5938.346781853993;6026.6986650667295;6049.516038716892;6104.17593624855;5857.502112320501;5864.648121112747;5865.35481097079;5934.37689042653;5939;6628.436582890508;6705.396514313637;6730.091437655951;6744.954532047443;6745.190577130776;6917.377436030782;6925.89611155823;7014.15830100381;7021.754238601625;7024.051753013482;6645.9003114954285;6648.767293144717;6698.66506674674;6721.193456785217;6757.358050985148;6629.867402652017;6723.125993620798;6781.321867813179;6801.251862295243;6835.248122706597;6954.165500140016;6963.93036069653;6964.338387853146;6969.616671083139;6975.267596902252;6715;6723.731050757915;6739.123913891162;6746.831329216809;6747.987801829765;6644.302348253557;6657.2677005528385;6659.733610655519;6733.091032710718;6750.8649827004165;6506.804795856132;6577.704003319766;6582.572132811352;6612;6691.130153080068;6593.571417857824;6600.504962127764;6623.205215374186;6626.90059649096;6637.070810086495;6294.874102518015;6349.7142628580905;6367.267764207176;6384.521160912857;6415.8396040097505 03:03:59 INFO - PID 3818 | |1;ext.html;753.6269546574447;856.8971723393191;880.533317341809;886.4459712679575;1152.2740673375772;29881.402371952576;30472.847635761816;39875.60124398755;40335.596644033554;40460;40296.79851600745;40389.59610403895;40528.18943621133;40594.59405405945;40848.79575602118;7224.67488962998;7537.208593097728;7722.459427840054;7734.535927844319;7848.960755196216;7659.578723170223;7766.223377662245;7771.727989520368;7776.455648104635;7778.377729781632;7752.418568607349;7820.41346898982;7821.061472623293;7824.843503129962;7827.7260295889655;7753.689852405897;7787.649555770004;7790.259925307087;7790.766277011699;7796.376289896794;7714.807129821743;7767.805804854867;7770.689172433096;7777.02787151606;7778.961105194495;36934;37132.44301335482;37168.07079823026;37222.06944826374;37228;3518.36669399508;3521.454174602941;3543.964560354389;3554.8400321985573;3559.4482855157494;30642.38715225683;30681.386372272653;30743.38513229723;30757.538636920464;30775.846120769478;5079.085764562377;5116.411612664517;5117.795288188468;5147.562457191143;5183.559397451221;185915;185934;186248;186268;186479;46593.99999999932;46645.76677116593;46723.29915051277;46759.29861052087;46825;48490.09059456444;48520;48774.512254876994;48888.51111488911;48906.51093489091;15511.224438778016;15653.373865045498;15668.68662626741;15672.608184795243;15704.214789260719;11668.824967625493;11771;12938.805917911239;13057.804132938014;13142.802857957138;13996.930015350063;14005.859941400455;14043.438262469386;14070.085444446078;14155.716885662228;5668.149777533403;5780.682062486603;5824.213731146326;5879.794207202813;5915.497182739516;985.6156099121348;1011.9848202276971;1020.5152552537634;1022.943738094412;1041.5833666533297;22723.204687836176;22807.657885131735;22929.426764331027;23274.06903723832;23296.534069318517;1111.822108462642;1118.6364431559775;1123.4888125902694;1124.3591153042687;1133.9716507087223;11910.166288359731;12217.144799863925;12227.082968777377;12302.507899683913;12327;234996;235782;236010.09974750425;236128;236179;50259.49740502548;50291.74854125779;50444.74777626088;50444.74777626162;50803.74598126986;1427.1437137717287;1478.3643033495484;1479.6976918905236;1481.6295926018495;1484.606579256498;22020.559588807813;22169.889150554143;22527.88736056309;22587.322380328616;22626.773732263126;35505;35541;35607.82196089003;35610.28779424449;35647.82176089103;194190;194947.05052949855;195167;195524;195775;31523.21191970219;32664.34671306513;33257.168570784954;33364.499532507514;33415.83292083621;40138.598614013485;41575.7921210392;41613;41657.79171104246;41785.58214417939;30746.846265768527;30864.228394290323;31178.844105779324;31187.53218701676;31570.842145789124;30596.235094122825;30693.53959690562;30807.69192308048;30834.845825770728;30910.53634195534;34866.47700284447;37488.812555937046;38231.617683822806;38258.234835303694;38352.80823595864;31585;32518.83740581282;32572;32702.672973270914;32833.83583082165;23109.306720798402;24005.759942400353;24153.396165096012;24501.754982449947;24534.87732561326 03:03:59 INFO - PID 3818 | |2;jquery.html;574.6781802190774;580.2080160580808;583.2388732703821;586.9677167755772;590.1767034986194;21433.57132857344;22192;22225.333240002827;22277.220297289598;22476.325710228677;25941.610875836832;26287;26417.075402360922;26461.338466538346;26478.073267435644;6421.550491465611;6534.24856141545;6572.5399222054475;6573.1783527059115;6585.045168450596;6372.553921225516;6496.837579060514;6646.700898459557;6978;6999.475039372042;7013.754518591851;7018.22799492058;7020.613866237355;7023.61370124643;7026.82432939178;7008.36924676779;7020.40326572242;7022.262662420423;7023.789286321418;7024.754133605325;6848.246692863781;6853.760118395882;6856.417204537571;6856.41720453762;6883.483738719566;29775.851120744475;30238.697613023807;31766.364672706415;32152.839235803905;32201.51697724536;1988.6917527783216;2052.6100040992164;2052.8460365472647;2054.3528788431595;2055.465578949484;18772.249110035584;19264.422067337862;19354.516137096543;19452.708209376728;19491.220351185937;2303.7120359955006;2370.7747763962398;2376.9049238030457;2434.6713193718797;2440.255722004788;137528.31235843757;141207;141454;141509.58490415066;141635.29182354125;20007.299744508873;20067.799322006595;21318.78681213199;21635.459113522287;22145.23877366374;42730;42749.78625106917;42774.358384624255;42956.78521607434;43008;11426.257293276076;11490.310581365145;11592.88407115935;11686.47410866513;11779.411029448493;9661.613535458644;9749.463779492055;9776.853347199798;9860.211183105333;9878.407295562289;9259.629614815332;10959;11261.774764504664;11359.432028398713;11426.542938282542;3577.4454959481322;3918.825052096114;4045.7977101144825;4080.5103387593085;4094.1545625790386;957.9808403831885;961.8701475300885;976.3019441099648;980.9068138526828;994.8756405449319;19400.902995484932;23616.05535778584;24873;24972.500549988898;25050.24849254525;1015.3755440404246;1032.7211652853687;1039.6309310194922;1048.9895101049044;1061.8991195836384;11299.435028248554;11617.593384231512;11618.360990145455;11646.76706465866;11761.059115270758;512910;515712;529545;534945;539831;121284.39357803155;121880.39059804645;123174;127902;131195.34402327926;1227.1532642476664;1228.4042239513828;1248.7252804383204;1261.3188878005958;1261.8864302212662;17985.280588776826;18030.459086227434;18206.45380638583;18270.360537381126;18468.44594662162;58775.4122458787;59625.70187149037;60438.39561604503;60633;60647;72100.63949680218;74510;75112.24887751271;75196.24803751892;76734.61632691801;12154.453049612965;12461;12889.548865789653;14698.853011469748;15072.397104115713;23060.654090188997;23103.306900793003;23198.1880634177;23264;24369.39076523101;16079.678406431573;16182.838171618134;16235.918820405823;18387;18456.907715461337;15817.68364632678;16934.830651693817;17485.912570437577;17893.673822403005;18043.639127217644;16653.750193747346;17480.21339039721;17578.560535986704;17611.55971100733;17872.642547149244;14793.482228121964;14917.776233356726;14993.625159371102;15034.849651503782;15082.698346033238;18772.906135469235;18819;18959;19055.714164287823;19181.136848842114 03:03:59 INFO - PID 3818 | |3;mootools.html;1364.4201214483844;1414.2575148047274;1417.1284659934145;1418.375914597577;1430.2419717549697;14846.331915063822;15485.922570387147;15498.535043948685;15501.612459688513;15755.212239388042;15805.762913556306;16104.19479026047;16126.274317655705;16131.274092665828;16138.919305403457;2829.165396208113;2877.7410033097017;2880.251134704971;2890.2196406970106;2891.34944637457;2863.4702580022763;2962.111366590025;2967.1840243932875;2968.465676178287;2969.8218106913655;2817.7745780337627;2832.830030198195;2840.318323602341;2862.127051249368;2879.064304101165;2797.2027972028077;2816.197383745633;2827.8303301801957;2829.6462942132234;2840.3609187932757;2740.8081434299606;2744.5745909384077;2754.0911499205213;2771.514984877638;2780;14856.554303370916;15230.695386092217;15238.542843714595;15252.618684532865;15310.617234569114;7791.961040194819;8015.519068855829;8016.318612917909;8022.037355517317;8025.357971362277;5332.600054995852;5549.306336707911;5579.944200557983;5636.887262254732;5650.632708873951;1946.7566554180728;1949.8147675970902;1953.8436925045962;1980.9405717828488;2005.3883565512513;26729.19812405631;26900.19299421001;26907.596386054225;26966.191014269603;27006.5949010765;6575.375339342851;7006.159260888726;7012.439004879598;7109.182444019004;7136.393406560397;16105.67788644244;16109.677806443806;16202.432914847925;16219.594510137105;16261.430849920196;7677.884831727529;7740.303372696484;7811.765647030598;7831.903533505314;7837.608119593998;7015.649217539102;7130.322619351154;7249.96375018132;7302.780916572511;7331.230220826843;5340.706261155596;5498.432946610277;5513.61404701675;5536.612437129363;5563.471470210324;1473.5284708893266;1488.1294442751112;1494.394770118104;1498.6178524476188;1502.8121484814399;896.9596368163318;942.9481378524247;944.6976967370375;957.2294303086082;961.6345788600287;7995.880061799077;8009.519428834288;8097.676092956216;8132.105468398471;8136.145704700921;728.6101935464501;730.8720973829664;733.9229380914927;740.8370158565107;746.1009483572283;5275.498827611371;5710;5779.422057794187;5799.797007104731;5834.737436815353;20734.688979665316;21075.578488430147;21952.121915123535;21980.89009554942;22018.229361972255;21123.26068587592;21242.150313987266;21763.45591360197;21781.891090544446;21800.672989905164;10449.268551201494;10687.091597214168;10745.247832651796;10817.56729730802;10836;8346.165383461605;8379.413441059067;8380.20388063133;8418.326533877398;8423.915760842314;17297;18290.542736431697;18295.359662411753;18414.72377914359;18718.812811871707;15377.114427860697;17642.206100725754;19520.60958780845;19775.307864224684;19779.307724229584;10162.949185254027;10628.149748020294;11252;11257.268277561932;11312.717182070184;13794.862051379358;13938.442462301395;14056.578302650936;14065;14071.366788494337;10999.450027498753;11069.723256918642;11369.147313951322;11577.59478418285;11588.47851846652;10867.184961127788;10959.945200273947;11045.171612129283;11272.774544509228;11432.657020289405;10410.635627752992;10616.363018218932;10635.574577017142;10647.627333043616;10677;7376.771441669396;7627.351675107456;7634.50375725576;8014.158513356014;8017.919820801717;7704.229577042251;7721.227877212234;7742.341900938483;7763.767086987399;7867.567283799446 03:03:59 INFO - PID 3818 | |4;prototype.html;498.2252597211336;509.30988510568204;532.2468706779906;539.6303532080527;540.8891177308651;6332.620042797435;6355.3962373574495;6399.976003839375;6403.231612206541;6451;6068.878622427549;6163.322034576204;6182.84542886427;6284.883401372109;6298.559100862942;5090.516400941905;5103.157978933472;5111.591072714174;5134.589232861381;5137.922931156036;5019.121653710605;5038.470960549144;5054.974725126369;5090.490950904898;5108.84673459795;4879.46325904152;4954.207326827708;5011.922436676114;5066.341375621182;5078.49215078491;5007.198848184291;5026.195808670595;5045.394552653705;5067.949320506821;5082.1360368737405;4523.570260825217;4535.455745310551;4543.727376357396;4570.954290457119;4580.152671755703;6221.377862213742;6241.843953901143;6243.157173781527;6277.2153480814895;6297.464715499188;5759.769609215627;5763.164341170549;5787.536997040227;5794.565407594448;5831.271091113611;6616.371444712745;6634.93365066348;6668.233153187397;6710.7315707371645;6721.294264102296;4867.610591152699;4873;4924.926126108111;4932.112219800434;4946.975265123688;6941.687624056879;6959;6999.985002174656;7038.436925045984;7049.224585295613;5019.799208031711;5021.2549141061345;5035.521625445577;5057.671251368649;5074.2642316864585;5083.694978301313;5159.793608255628;5163.586913046947;5178.737395440849;5182;5814.651120932757;5830.329512106077;5840.8539786504825;5841.065429531254;5850.619709718854;6711.328867113347;6784.626845523445;6785.9660701696175;6816.659167041628;6824.863502730017;1683.6380178261732;1711.7090094683933;1717.957051073708;1759.3754217252947;1767.3549154558493;1182.781185480689;1231.095145068374;1239.2192918461312;1249.3066348176849;1253.8432695913011;5930.377310382434;5936.7922122725495;5939.851503712442;5946.10808378738;5950.761969521257;6827.112475378269;6832.437835811571;6837.418819400307;6846.760363387258;6891.138607674041;5898.351181370046;5914;5919.437653422918;5927.318358388754;5974.492168165754;3586.6234045425376;3615.204654975901;3620.837062332201;3626.546681664792;3628.2017956050154;6522.380373864476;6545.541812073205;6547.9672601636685;6571.507136964651;6593.5384523083885;7001.7944347530365;7020.227774944752;7024.964875175694;7044.696731104762;7046.154461464657;6580.177477815273;6585.2426970898005;6586.901196482057;6631.933680663132;6669.1330127083165;6794.388505034571;6822.556533825484;6834.419074378535;6835.624040677611;6848.7260509579055;6729.831754206184;6732.427743641845;6762.560433571802;6787.524873258824;6791.694373753094;6615.470762338905;6636.601803891782;6649.501287403367;6653.401193892573;6670.266270710121;5092.516210959953;5098.923516147187;5102.311187989722;5108.48915108501;5121.129408000724;6808.455323574201;6833.93166068333;6883.27725588826;6933.757318493828;6965.512414131165;6546.672666366758;6575.178102737158;6591.802245932629;6615.636140012345;6616.338366163538;6060.484858787053;6571.57284776488;6595.614920866672;6606.513534454709;6650.068990341453;6552.6723663815665;6557.409833115043;6572.967135164294;6584.242812076673;6595.340465953366;6690.330966903465;6696.866062678621;6698.665066746546;6702.664866756741;6705.8264803658185;6328.0824280478155;6421.97707722066;6423.261324947691;6473.643949582818;6474.935250647622 03:03:59 INFO - PID 3818 | |5;yui.html;906.5875026862778;913.4382354851768;926.6154545863467;930.1070971867007;932.3892850183128;18442.354517591888;19220.327288544922;19251.71122433161;19257.614847703037;21466.463338416546;20004.199832006703;21014.89492552539;21282;21299.787002129975;21387.35837924861;974.439697174126;988.6391467114487;993.3046867192958;993.5181437003048;994.060612720979;949.8907625623073;950;950.8478643417054;953.0326718380849;1002.7743757654542;938.8638647396158;981.5190556627236;986.8371718666448;1040.8438734189895;1061.925665203436;973.2700474644017;974.1476208317722;975.1029053271008;975.2052077556768;1029.583018877352;758.5297115788192;759.3279947246726;760.4182800157884;761.1247065874234;762.4624639629104;17609.823901760945;18895.527611809677;20020.79979200204;20294.391168264974;20304.390868273975;20223.696644550342;20589.279375221795;20915.477113072142;21385.144594216214;21409.785902140935;16972.15139243033;17042.82957170425;17065.65868682632;17104.059276739776;17109.401170958958;3760.8683696070507;3799.4300854871713;3800.20195758891;3801.3157631626295;3802.4676545283683;126878;127480;131063;131695.34152329274;133104.33447832795;13900.652483687989;14019.439222431192;14317.57047288583;14415.711685766435;14417.13497190172;29868.850655747017;30987.225319366746;31580.052598422084;31661.050168494523;31698.524522132186;7573.924260757432;7774.1837107104075;8137.715179968673;8234.876476852853;8250.092489826115;3924.4113382992778;4099.795010249476;4103.158852435244;4106.1992911382595;4108.8356465741035;5104.489551044866;5184.403793563713;5239.423663397023;5251.737413129328;5257.526822585985;1065.3235195650818;1082.4479515447179;1083.0793825248477;1086.9673909782719;1087.494315143462;682.5972676121112;687.5737043033251;689.3898899474002;689.6172624193521;709.4147328454046;12043.27740335565;12209.328486933126;12231.204971676812;12709.555165569162;12871.292078935749;699.8285420072115;701.386286998876;703.4196787650209;720.4164626652429;722.4978639845363;5419.729013549307;5441.401445840955;6123.877522449615;6213.502919766408;6320.62076275425;104100.47949760202;104731;106955;108474;114881;94449.52775236218;95006;96209.51895240479;97031.51484242534;101230.49384753176;79769;81339;82457;90418;90771.54614226887;12021.639350819489;12501.624951251477;12538.373081346079;12615.558455454015;12968;38947;39736.60263397329;42178;42326.788366057976;42402.78798605987;52258.47741522536;63179.36820631735;73060.63469682618;73381.6330918342;73418;17408.39070632522;17505.912470437564;17587.912060439616;17744.733828992317;17802.643947120727;20427;20452.488687782923;20472.69290960607;20525.17899284012;21384.679229811252;18552.350667726565;19034.428967131007;19042.52393690169;19163;19248.326308579133;18348;18813.341533046827;18871.245150193838;19009.619807604045;19458.805411946265;18362.540936476697;18423.170957306684;18733.625327493646;18741.250349985847;19081.332153374566;13185.80221296662;13446.260455674641;13529.72940541164;13631.977601679715;13740.244286564335;12558.937205313914;12608.117431779687;13293.135946163855;13833.584992450242;13927.860721392657 03:03:59 INFO - PID 3818 | __end_tp_report 03:03:59 INFO - PID 3818 | __start_cc_report 03:03:59 INFO - PID 3818 | _x_x_mozilla_cycle_collect,392 03:03:59 INFO - PID 3818 | __end_cc_report 03:03:59 INFO - PID 3818 | __startTimestamp1504433039834__endTimestamp 03:03:59 INFO - PID 3818 | 03:03:59 INFO - PID 3818 | ------- Summary: start ------- 03:03:59 INFO - PID 3818 | Number of tests: 6 03:03:59 INFO - PID 3818 | 03:03:59 INFO - PID 3818 | [#0] dojo.html Cycles:165 Average:6307.19 Median:6593.57 stddev:842.88 (12.8%) stddev-sans-first:812.70 03:03:59 INFO - PID 3818 | Values: 3340.5 3356.1 3360.7 3381.9 3392.4 6361.6 6447.0 6467.2 6483.3 6486.6 6325.7 6385.3 6413.0 6577.3 6611.7 5912.6 5967.9 6018.0 6041.7 6096.1 1050.1 5781.3 5850.2 5926.3 5930.9 5832.5 5844.0 5926.3 5945.4 5956.4 5839.2 5854.4 5929.7 5936.4 5957.2 5820.9 5832.9 5892.3 5901.0 5909.3 6302.9 6371.9 6440.5 6458.3 6466.4 6786.0 6810.0 6844.0 6860.7 6866.4 6929.3 6936.4 6946.2 7012.3 7021.9 6454.3 6481.2 6548.3 6553.8 6554.0 6993.2 7073.3 7079.0 7089.1 7091.4 6732.9 6744.5 6745.7 6752.9 6763.2 6843.6 6874.3 6878.7 6879.1 6881.5 6785.0 6813.2 6853.9 6888.7 6924.0 6678.9 6748.2 6838.8 6849.3 6938.7 5374.5 5378.3 5435.5 5437.9 5439.2 4713.2 4723.6 4738.7 4790.4 4798.7 6015.2 6093.9 6099.8 6107.5 6117.6 6860.3 6881.3 6959.8 6984.0 7006.2 5913.7 5938.3 6026.7 6049.5 6104.2 5857.5 5864.6 5865.4 5934.4 5939.0 6628.4 6705.4 6730.1 6745.0 6745.2 6917.4 6925.9 7014.2 7021.8 7024.1 6645.9 6648.8 6698.7 6721.2 6757.4 6629.9 6723.1 6781.3 6801.3 6835.2 6954.2 6963.9 6964.3 6969.6 6975.3 6715.0 6723.7 6739.1 6746.8 6748.0 6644.3 6657.3 6659.7 6733.1 6750.9 6506.8 6577.7 6582.6 6612.0 6691.1 6593.6 6600.5 6623.2 6626.9 6637.1 6294.9 6349.7 6367.3 6384.5 6415.8 03:03:59 INFO - PID 3818 | 03:03:59 INFO - PID 3818 | [#1] ext.html Cycles:180 Average:36963.82 Median:33390.17 stddev:53565.03 (160.4%) stddev-sans-first:53646.29 03:03:59 INFO - PID 3818 | Values: 753.6 856.9 880.5 886.4 1152.3 29881.4 30472.8 39875.6 40335.6 40460.0 40296.8 40389.6 40528.2 40594.6 40848.8 7224.7 7537.2 7722.5 7734.5 7849.0 7659.6 7766.2 7771.7 7776.5 7778.4 7752.4 7820.4 7821.1 7824.8 7827.7 7753.7 7787.6 7790.3 7790.8 7796.4 7714.8 7767.8 7770.7 7777.0 7779.0 36934.0 37132.4 37168.1 37222.1 37228.0 3518.4 3521.5 3544.0 3554.8 3559.4 30642.4 30681.4 30743.4 30757.5 30775.8 5079.1 5116.4 5117.8 5147.6 5183.6 185915.0 185934.0 186248.0 186268.0 186479.0 46594.0 46645.8 46723.3 46759.3 46825.0 48490.1 48520.0 48774.5 48888.5 48906.5 15511.2 15653.4 15668.7 15672.6 15704.2 11668.8 11771.0 12938.8 13057.8 13142.8 13996.9 14005.9 14043.4 14070.1 14155.7 5668.1 5780.7 5824.2 5879.8 5915.5 985.6 1012.0 1020.5 1022.9 1041.6 22723.2 22807.7 22929.4 23274.1 23296.5 1111.8 1118.6 1123.5 1124.4 1134.0 11910.2 12217.1 12227.1 12302.5 12327.0 234996.0 235782.0 236010.1 236128.0 236179.0 50259.5 50291.7 50444.7 50444.7 50803.7 1427.1 1478.4 1479.7 1481.6 1484.6 22020.6 22169.9 22527.9 22587.3 22626.8 35505.0 35541.0 35607.8 35610.3 35647.8 194190.0 194947.1 195167.0 195524.0 195775.0 31523.2 32664.3 33257.2 33364.5 33415.8 40138.6 41575.8 41613.0 41657.8 41785.6 30746.8 30864.2 31178.8 31187.5 31570.8 30596.2 30693.5 30807.7 30834.8 30910.5 34866.5 37488.8 38231.6 38258.2 38352.8 31585.0 32518.8 32572.0 32702.7 32833.8 23109.3 24005.8 24153.4 24501.8 24534.9 03:03:59 INFO - PID 3818 | 03:03:59 INFO - PID 3818 | [#2] jquery.html Cycles:180 Average:37343.69 Median:12682.18 stddev:88516.49 (698.0%) stddev-sans-first:88721.75 03:03:59 INFO - PID 3818 | Values: 574.7 580.2 583.2 587.0 590.2 21433.6 22192.0 22225.3 22277.2 22476.3 25941.6 26287.0 26417.1 26461.3 26478.1 6421.6 6534.2 6572.5 6573.2 6585.0 6372.6 6496.8 6646.7 6978.0 6999.5 7013.8 7018.2 7020.6 7023.6 7026.8 7008.4 7020.4 7022.3 7023.8 7024.8 6848.2 6853.8 6856.4 6856.4 6883.5 29775.9 30238.7 31766.4 32152.8 32201.5 1988.7 2052.6 2052.8 2054.4 2055.5 18772.2 19264.4 19354.5 19452.7 19491.2 2303.7 2370.8 2376.9 2434.7 2440.3 137528.3 141207.0 141454.0 141509.6 141635.3 20007.3 20067.8 21318.8 21635.5 22145.2 42730.0 42749.8 42774.4 42956.8 43008.0 11426.3 11490.3 11592.9 11686.5 11779.4 9661.6 9749.5 9776.9 9860.2 9878.4 9259.6 10959.0 11261.8 11359.4 11426.5 3577.4 3918.8 4045.8 4080.5 4094.2 958.0 961.9 976.3 980.9 994.9 19400.9 23616.1 24873.0 24972.5 25050.2 1015.4 1032.7 1039.6 1049.0 1061.9 11299.4 11617.6 11618.4 11646.8 11761.1 512910.0 515712.0 529545.0 534945.0 539831.0 121284.4 121880.4 123174.0 127902.0 131195.3 1227.2 1228.4 1248.7 1261.3 1261.9 17985.3 18030.5 18206.5 18270.4 18468.4 58775.4 59625.7 60438.4 60633.0 60647.0 72100.6 74510.0 75112.2 75196.2 76734.6 12154.5 12461.0 12889.5 14698.9 15072.4 23060.7 23103.3 23198.2 23264.0 24369.4 16079.7 16182.8 16235.9 18387.0 18456.9 15817.7 16934.8 17485.9 17893.7 18043.6 16653.8 17480.2 17578.6 17611.6 17872.6 14793.5 14917.8 14993.6 15034.8 15082.7 18772.9 18819.0 18959.0 19055.7 19181.1 03:03:59 INFO - PID 3818 | 03:03:59 INFO - PID 3818 | [#3] mootools.html Cycles:180 Average:9375.04 Median:2742.69 stddev:6618.03 (241.3%) stddev-sans-first:6609.23 03:03:59 INFO - PID 3818 | Values: 1364.4 1414.3 1417.1 1418.4 1430.2 14846.3 15485.9 15498.5 15501.6 15755.2 15805.8 16104.2 16126.3 16131.3 16138.9 2829.2 2877.7 2880.3 2890.2 2891.3 2863.5 2962.1 2967.2 2968.5 2969.8 2817.8 2832.8 2840.3 2862.1 2879.1 2797.2 2816.2 2827.8 2829.6 2840.4 2740.8 2744.6 2754.1 2771.5 2780.0 14856.6 15230.7 15238.5 15252.6 15310.6 7792.0 8015.5 8016.3 8022.0 8025.4 5332.6 5549.3 5579.9 5636.9 5650.6 1946.8 1949.8 1953.8 1980.9 2005.4 26729.2 26900.2 26907.6 26966.2 27006.6 6575.4 7006.2 7012.4 7109.2 7136.4 16105.7 16109.7 16202.4 16219.6 16261.4 7677.9 7740.3 7811.8 7831.9 7837.6 7015.6 7130.3 7250.0 7302.8 7331.2 5340.7 5498.4 5513.6 5536.6 5563.5 1473.5 1488.1 1494.4 1498.6 1502.8 897.0 942.9 944.7 957.2 961.6 7995.9 8009.5 8097.7 8132.1 8136.1 728.6 730.9 733.9 740.8 746.1 5275.5 5710.0 5779.4 5799.8 5834.7 20734.7 21075.6 21952.1 21980.9 22018.2 21123.3 21242.2 21763.5 21781.9 21800.7 10449.3 10687.1 10745.2 10817.6 10836.0 8346.2 8379.4 8380.2 8418.3 8423.9 17297.0 18290.5 18295.4 18414.7 18718.8 15377.1 17642.2 19520.6 19775.3 19779.3 10162.9 10628.1 11252.0 11257.3 11312.7 13794.9 13938.4 14056.6 14065.0 14071.4 10999.5 11069.7 11369.1 11577.6 11588.5 10867.2 10959.9 11045.2 11272.8 11432.7 10410.6 10616.4 10635.6 10647.6 10677.0 7376.8 7627.4 7634.5 8014.2 8017.9 7704.2 7721.2 7742.3 7763.8 7867.6 03:03:59 INFO - PID 3818 | 03:03:59 INFO - PID 3818 | [#4] prototype.html Cycles:180 Average:5605.80 Median:6242.50 stddev:1579.96 (25.3%) stddev-sans-first:1537.18 03:03:59 INFO - PID 3818 | Values: 498.2 509.3 532.2 539.6 540.9 6332.6 6355.4 6400.0 6403.2 6451.0 6068.9 6163.3 6182.8 6284.9 6298.6 5090.5 5103.2 5111.6 5134.6 5137.9 5019.1 5038.5 5055.0 5090.5 5108.8 4879.5 4954.2 5011.9 5066.3 5078.5 5007.2 5026.2 5045.4 5067.9 5082.1 4523.6 4535.5 4543.7 4571.0 4580.2 6221.4 6241.8 6243.2 6277.2 6297.5 5759.8 5763.2 5787.5 5794.6 5831.3 6616.4 6634.9 6668.2 6710.7 6721.3 4867.6 4873.0 4924.9 4932.1 4947.0 6941.7 6959.0 7000.0 7038.4 7049.2 5019.8 5021.3 5035.5 5057.7 5074.3 5083.7 5159.8 5163.6 5178.7 5182.0 5814.7 5830.3 5840.9 5841.1 5850.6 6711.3 6784.6 6786.0 6816.7 6824.9 1683.6 1711.7 1718.0 1759.4 1767.4 1182.8 1231.1 1239.2 1249.3 1253.8 5930.4 5936.8 5939.9 5946.1 5950.8 6827.1 6832.4 6837.4 6846.8 6891.1 5898.4 5914.0 5919.4 5927.3 5974.5 3586.6 3615.2 3620.8 3626.5 3628.2 6522.4 6545.5 6548.0 6571.5 6593.5 7001.8 7020.2 7025.0 7044.7 7046.2 6580.2 6585.2 6586.9 6631.9 6669.1 6794.4 6822.6 6834.4 6835.6 6848.7 6729.8 6732.4 6762.6 6787.5 6791.7 6615.5 6636.6 6649.5 6653.4 6670.3 5092.5 5098.9 5102.3 5108.5 5121.1 6808.5 6833.9 6883.3 6933.8 6965.5 6546.7 6575.2 6591.8 6615.6 6616.3 6060.5 6571.6 6595.6 6606.5 6650.1 6552.7 6557.4 6573.0 6584.2 6595.3 6690.3 6696.9 6698.7 6702.7 6705.8 6328.1 6422.0 6423.3 6473.6 6474.9 03:03:59 INFO - PID 3818 | 03:03:59 INFO - PID 3818 | [#5] yui.html Cycles:180 Average:23685.41 Median:21384.91 stddev:32138.31 (150.3%) stddev-sans-first:32182.95 03:03:59 INFO - PID 3818 | Values: 906.6 913.4 926.6 930.1 932.4 18442.4 19220.3 19251.7 19257.6 21466.5 20004.2 21014.9 21282.0 21299.8 21387.4 974.4 988.6 993.3 993.5 994.1 949.9 950.0 950.8 953.0 1002.8 938.9 981.5 986.8 1040.8 1061.9 973.3 974.1 975.1 975.2 1029.6 758.5 759.3 760.4 761.1 762.5 17609.8 18895.5 20020.8 20294.4 20304.4 20223.7 20589.3 20915.5 21385.1 21409.8 16972.2 17042.8 17065.7 17104.1 17109.4 3760.9 3799.4 3800.2 3801.3 3802.5 126878.0 127480.0 131063.0 131695.3 133104.3 13900.7 14019.4 14317.6 14415.7 14417.1 29868.9 30987.2 31580.1 31661.1 31698.5 7573.9 7774.2 8137.7 8234.9 8250.1 3924.4 4099.8 4103.2 4106.2 4108.8 5104.5 5184.4 5239.4 5251.7 5257.5 1065.3 1082.4 1083.1 1087.0 1087.5 682.6 687.6 689.4 689.6 709.4 12043.3 12209.3 12231.2 12709.6 12871.3 699.8 701.4 703.4 720.4 722.5 5419.7 5441.4 6123.9 6213.5 6320.6 104100.5 104731.0 106955.0 108474.0 114881.0 94449.5 95006.0 96209.5 97031.5 101230.5 79769.0 81339.0 82457.0 90418.0 90771.5 12021.6 12501.6 12538.4 12615.6 12968.0 38947.0 39736.6 42178.0 42326.8 42402.8 52258.5 63179.4 73060.6 73381.6 73418.0 17408.4 17505.9 17587.9 17744.7 17802.6 20427.0 20452.5 20472.7 20525.2 21384.7 18552.4 19034.4 19042.5 19163.0 19248.3 18348.0 18813.3 18871.2 19009.6 19458.8 18362.5 18423.2 18733.6 18741.3 19081.3 13185.8 13446.3 13529.7 13632.0 13740.2 12558.9 12608.1 13293.1 13833.6 13927.9 03:03:59 INFO - PID 3818 | -------- Summary: end -------- 03:03:59 INFO - PID 3818 | 03:04:00 INFO - TEST-INFO | 3818: exit 0 03:04:00 INFO - TEST-OK | dromaeo_css | took 1113978ms 03:04:00 INFO - TEST-START | kraken 03:04:00 INFO - Initialising browser for kraken test... 03:04:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38445/getInfo.html -profile /tmp/tmpO03RjR/profile 03:04:00 INFO - TEST-INFO | started process 4193 (/builds/slave/test/build/application/firefox/firefox http://localhost:38445/getInfo.html) 03:04:05 INFO - TEST-INFO | 4193: exit 0 03:04:05 INFO - Browser initialized. 03:04:05 INFO - Running cycle 1/1 for kraken test... 03:04:05 INFO - Using env: {'DISPLAY': ':0', 03:04:05 INFO - 'HOME': '/home/cltbld', 03:04:05 INFO - 'JSGC_DISABLE_POISONING': '1', 03:04:05 INFO - 'LANG': 'en_US.UTF-8', 03:04:05 INFO - 'LANGUAGE': 'en_US:en', 03:04:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:04:05 INFO - 'LOGNAME': 'cltbld', 03:04:05 INFO - 'MAIL': '/var/mail/cltbld', 03:04:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:04:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:04:05 INFO - 'MOZ_CRASHREPORTER': '1', 03:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:04:06 INFO - 'MOZ_NO_REMOTE': '1', 03:04:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:04:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:06 INFO - 'NO_EM_RESTART': '1', 03:04:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:06 INFO - 'PWD': '/builds/slave/test', 03:04:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:04:06 INFO - 'RUST_BACKTRACE': 'full', 03:04:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:04:06 INFO - 'SHELL': '/bin/bash', 03:04:06 INFO - 'SHLVL': '1', 03:04:06 INFO - 'TERM': 'linux', 03:04:06 INFO - 'TMOUT': '86400', 03:04:06 INFO - 'USER': 'cltbld', 03:04:06 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746', 03:04:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:06 INFO - '_': '/tools/buildbot/bin/python'} 03:04:06 INFO - TEST-INFO | started process 4378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO03RjR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:04:06 INFO - PID 4378 | 03:04:06 INFO - PID 4378 | (/builds/slave/test/build/application/firefox/firefox:4430): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:04:06 INFO - PID 4378 | 03:04:17 INFO - PID 4378 | RSS: Main: 169984000 03:04:17 INFO - PID 4378 | 03:04:19 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38445/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:20 INFO - PID 4378 | RSS: Main: 175845376 03:04:20 INFO - PID 4378 | 03:04:23 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38445/tests/kraken/driver.html?testName=audio-dft) 03:04:24 INFO - PID 4378 | RSS: Main: 176340992 03:04:24 INFO - PID 4378 | 03:04:27 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38445/tests/kraken/driver.html?testName=audio-fft) 03:04:27 INFO - PID 4378 | RSS: Main: 169603072 03:04:27 INFO - PID 4378 | 03:04:29 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38445/tests/kraken/driver.html?testName=audio-oscillator) 03:04:30 INFO - PID 4378 | RSS: Main: 170094592 03:04:30 INFO - PID 4378 | 03:04:32 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38445/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:04:33 INFO - PID 4378 | RSS: Main: 170360832 03:04:33 INFO - PID 4378 | 03:04:36 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38445/tests/kraken/driver.html?testName=imaging-darkroom) 03:04:36 INFO - PID 4378 | RSS: Main: 172978176 03:04:36 INFO - PID 4378 | 03:04:40 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38445/tests/kraken/driver.html?testName=imaging-desaturate) 03:04:41 INFO - PID 4378 | RSS: Main: 172883968 03:04:41 INFO - PID 4378 | 03:04:43 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38445/tests/kraken/driver.html?testName=json-parse-financial) 03:04:44 INFO - PID 4378 | RSS: Main: 173404160 03:04:44 INFO - PID 4378 | 03:04:46 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38445/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:04:46 INFO - PID 4378 | RSS: Main: 172978176 03:04:46 INFO - PID 4378 | 03:04:48 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38445/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:04:49 INFO - PID 4378 | RSS: Main: 173457408 03:04:49 INFO - PID 4378 | 03:04:51 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38445/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:04:51 INFO - PID 4378 | RSS: Main: 170356736 03:04:51 INFO - PID 4378 | 03:04:54 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38445/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:04:55 INFO - PID 4378 | RSS: Main: 170336256 03:04:55 INFO - PID 4378 | 03:04:58 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38445/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:04:58 INFO - PID 4378 | RSS: Main: 171360256 03:04:58 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | Cycle 1(1): loaded http://localhost:38445/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38445/tests/kraken/driver.html?testName=ai-astar) 03:05:00 INFO - PID 4378 | RSS: Main: 172257280 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | __start_tp_report 03:05:00 INFO - PID 4378 | _x_x_mozilla_page_load 03:05:00 INFO - PID 4378 | _x_x_mozilla_page_load_details 03:05:00 INFO - PID 4378 | |i|pagename|runs| 03:05:00 INFO - PID 4378 | |0;ai-astar;101;108;95;108;108;111;108;107;109;109 03:05:00 INFO - PID 4378 | |1;audio-beat-detection;149;118;144;178;115;150;139;118;169;117 03:05:00 INFO - PID 4378 | |2;audio-dft;163;162;161;163;161;160;159;162;171;163 03:05:00 INFO - PID 4378 | |3;audio-fft;75;74;80;173;79;74;74;73;178;74 03:05:00 INFO - PID 4378 | |4;audio-oscillator;79;79;133;79;79;80;132;78;78;126 03:05:00 INFO - PID 4378 | |5;imaging-gaussian-blur;114;115;114;114;113;114;114;114;115;114 03:05:00 INFO - PID 4378 | |6;imaging-darkroom;150;148;148;148;147;149;148;148;150;149 03:05:00 INFO - PID 4378 | |7;imaging-desaturate;93;94;95;94;93;94;94;93;94;93 03:05:00 INFO - PID 4378 | |8;json-parse-financial;53;53;53;52;53;54;53;55;53;54 03:05:00 INFO - PID 4378 | |9;json-stringify-tinderbox;40;38;37;38;38;38;38;38;38;39 03:05:00 INFO - PID 4378 | |10;stanford-crypto-aes;75;75;74;74;77;75;79;73;75;76 03:05:00 INFO - PID 4378 | |11;stanford-crypto-ccm;126;124;123;122;119;121;122;129;116;157 03:05:00 INFO - PID 4378 | |12;stanford-crypto-pbkdf2;172;172;179;172;176;171;181;166;165;167 03:05:00 INFO - PID 4378 | |13;stanford-crypto-sha256-iterative;63;64;63;63;63;65;65;63;64;69 03:05:00 INFO - PID 4378 | __end_tp_report 03:05:00 INFO - PID 4378 | __start_cc_report 03:05:00 INFO - PID 4378 | _x_x_mozilla_cycle_collect,964 03:05:00 INFO - PID 4378 | __end_cc_report 03:05:00 INFO - PID 4378 | __startTimestamp1504433100701__endTimestamp 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | ------- Summary: start ------- 03:05:00 INFO - PID 4378 | Number of tests: 14 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#0] ai-astar Cycles:10 Average:106.40 Median:108.50 stddev:4.77 (4.4%) stddev-sans-first:4.64 03:05:00 INFO - PID 4378 | Values: 101.0 108.0 95.0 108.0 108.0 111.0 108.0 107.0 109.0 109.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:146.50 stddev:22.62 (15.4%) stddev-sans-first:23.74 03:05:00 INFO - PID 4378 | Values: 149.0 118.0 144.0 178.0 115.0 150.0 139.0 118.0 169.0 117.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:3.27 (2.0%) stddev-sans-first:3.47 03:05:00 INFO - PID 4378 | Values: 163.0 162.0 161.0 163.0 161.0 160.0 159.0 162.0 171.0 163.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#3] audio-fft Cycles:10 Average:95.40 Median:74.00 stddev:42.30 (57.2%) stddev-sans-first:44.21 03:05:00 INFO - PID 4378 | Values: 75.0 74.0 80.0 173.0 79.0 74.0 74.0 73.0 178.0 74.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:79.00 stddev:24.94 (31.6%) stddev-sans-first:25.83 03:05:00 INFO - PID 4378 | Values: 79.0 79.0 133.0 79.0 79.0 80.0 132.0 78.0 78.0 126.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 03:05:00 INFO - PID 4378 | Values: 114.0 115.0 114.0 114.0 113.0 114.0 114.0 114.0 115.0 114.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:148.50 stddev:0.97 (0.7%) stddev-sans-first:0.87 03:05:00 INFO - PID 4378 | Values: 150.0 148.0 148.0 148.0 147.0 149.0 148.0 148.0 150.0 149.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 03:05:00 INFO - PID 4378 | Values: 93.0 94.0 95.0 94.0 93.0 94.0 94.0 93.0 94.0 93.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 03:05:00 INFO - PID 4378 | Values: 53.0 53.0 53.0 52.0 53.0 54.0 53.0 55.0 53.0 54.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.79 (2.1%) stddev-sans-first:0.50 03:05:00 INFO - PID 4378 | Values: 40.0 38.0 37.0 38.0 38.0 38.0 38.0 38.0 38.0 39.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:75.00 stddev:1.70 (2.3%) stddev-sans-first:1.80 03:05:00 INFO - PID 4378 | Values: 75.0 75.0 74.0 74.0 77.0 75.0 79.0 73.0 75.0 76.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:123.50 stddev:11.49 (9.3%) stddev-sans-first:12.19 03:05:00 INFO - PID 4378 | Values: 126.0 124.0 123.0 122.0 119.0 121.0 122.0 129.0 116.0 157.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.10 Median:172.00 stddev:5.34 (3.1%) stddev-sans-first:5.67 03:05:00 INFO - PID 4378 | Values: 172.0 172.0 179.0 172.0 176.0 171.0 181.0 166.0 165.0 167.0 03:05:00 INFO - PID 4378 | 03:05:00 INFO - PID 4378 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:1.87 (2.9%) stddev-sans-first:1.94 03:05:00 INFO - PID 4378 | Values: 63.0 64.0 63.0 63.0 63.0 65.0 65.0 63.0 64.0 69.0 03:05:00 INFO - PID 4378 | -------- Summary: end -------- 03:05:00 INFO - PID 4378 | 03:05:01 INFO - PID 4378 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:05:01 INFO - TEST-INFO | 4378: exit 0 03:05:01 INFO - TEST-OK | kraken | took 60788ms 03:05:01 INFO - SUITE-END | took 1174s 03:05:01 INFO - Completed test suite (00:19:35) 03:05:01 INFO - javascript benchmark 03:05:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8681.857305930218, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.4989251612255, 3356.1106306828697, 3360.747943904207, 3381.8647254109837, 3392.3554524640317, 6361.618302901829, 6446.967765161174, 6467.191601049869, 6483.319251478598, 6486.6432346221, 6325.7153428095735, 6385.297617262097, 6413.0, 6577.342265773432, 6611.735530578771, 5912.586118971673, 5967.880642387149, 6017.969910150443, 6041.728122234488, 6096.116063170837, 1050.1421163401244, 5781.335146458148, 5850.210221620069, 5926.3184733755525, 5930.911036334458, 5832.504237139849, 5844.0, 5926.259217597801, 5945.375735547748, 5956.404359564074, 5839.240898683186, 5854.443827836349, 5929.673867937262, 5936.406359364029, 5957.225560677128, 5820.912686309709, 5832.912506312408, 5892.263467066617, 5900.970495147497, 5909.290885093773, 6302.9054564181115, 6371.936280637227, 6440.5491615586925, 6458.257300410466, 6466.418022377961, 6786.0, 6809.965950170267, 6844.041834143224, 6860.725570977155, 6866.416354609864, 6929.3070692930805, 6936.375726184617, 6946.2011868635245, 7012.263712310185, 7021.894671579879, 6454.322296158883, 6481.189851268591, 6548.27968923418, 6553.770618028346, 6554.0, 6993.160820701549, 7073.328033836778, 7078.97354883539, 7089.14930208368, 7091.397231235403, 6732.932670673231, 6744.494162937799, 6745.730170793114, 6752.932470675329, 6763.222229443579, 6843.623600702009, 6874.2782007889455, 6878.65606719662, 6879.140107486564, 6881.483888708367, 6785.0, 6813.182418109858, 6853.897191542131, 6888.655567221619, 6923.9653801730665, 6678.866422671519, 6748.223954245226, 6838.760643377458, 6849.280825513349, 6938.722451101899, 5374.516293533601, 5378.2739330189925, 5435.510804027656, 5437.864053398696, 5439.156930675752, 4713.151632706077, 4723.574878260904, 4738.715677059387, 4790.449098353664, 4798.712077275374, 6015.157877897098, 6093.939060609426, 6099.75600975956, 6107.48086412651, 6117.571769976148, 6860.279670634612, 6881.311868813179, 6959.826004349932, 6984.0, 7006.159260888624, 5913.733881975321, 5938.346781853993, 6026.6986650667295, 6049.516038716892, 6104.17593624855, 5857.502112320501, 5864.648121112747, 5865.35481097079, 5934.37689042653, 5939.0, 6628.436582890508, 6705.396514313637, 6730.091437655951, 6744.954532047443, 6745.190577130776, 6917.377436030782, 6925.89611155823, 7014.15830100381, 7021.754238601625, 7024.051753013482, 6645.9003114954285, 6648.767293144717, 6698.66506674674, 6721.193456785217, 6757.358050985148, 6629.867402652017, 6723.125993620798, 6781.321867813179, 6801.251862295243, 6835.248122706597, 6954.165500140016, 6963.93036069653, 6964.338387853146, 6969.616671083139, 6975.267596902252, 6715.0, 6723.731050757915, 6739.123913891162, 6746.831329216809, 6747.987801829765, 6644.302348253557, 6657.2677005528385, 6659.733610655519, 6733.091032710718, 6750.8649827004165, 6506.804795856132, 6577.704003319766, 6582.572132811352, 6612.0, 6691.130153080068, 6593.571417857824, 6600.504962127764, 6623.205215374186, 6626.90059649096, 6637.070810086495, 6294.874102518015, 6349.7142628580905, 6367.267764207176, 6384.521160912857, 6415.8396040097505], "value": 6248.850664834145, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.6269546574447, 856.8971723393191, 880.533317341809, 886.4459712679575, 1152.2740673375772, 29881.402371952576, 30472.847635761816, 39875.60124398755, 40335.596644033554, 40460.0, 40296.79851600745, 40389.59610403895, 40528.18943621133, 40594.59405405945, 40848.79575602118, 7224.67488962998, 7537.208593097728, 7722.459427840054, 7734.535927844319, 7848.960755196216, 7659.578723170223, 7766.223377662245, 7771.727989520368, 7776.455648104635, 7778.377729781632, 7752.418568607349, 7820.41346898982, 7821.061472623293, 7824.843503129962, 7827.7260295889655, 7753.689852405897, 7787.649555770004, 7790.259925307087, 7790.766277011699, 7796.376289896794, 7714.807129821743, 7767.805804854867, 7770.689172433096, 7777.02787151606, 7778.961105194495, 36934.0, 37132.44301335482, 37168.07079823026, 37222.06944826374, 37228.0, 3518.36669399508, 3521.454174602941, 3543.964560354389, 3554.8400321985573, 3559.4482855157494, 30642.38715225683, 30681.386372272653, 30743.38513229723, 30757.538636920464, 30775.846120769478, 5079.085764562377, 5116.411612664517, 5117.795288188468, 5147.562457191143, 5183.559397451221, 185915.0, 185934.0, 186248.0, 186268.0, 186479.0, 46593.99999999932, 46645.76677116593, 46723.29915051277, 46759.29861052087, 46825.0, 48490.09059456444, 48520.0, 48774.512254876994, 48888.51111488911, 48906.51093489091, 15511.224438778016, 15653.373865045498, 15668.68662626741, 15672.608184795243, 15704.214789260719, 11668.824967625493, 11771.0, 12938.805917911239, 13057.804132938014, 13142.802857957138, 13996.930015350063, 14005.859941400455, 14043.438262469386, 14070.085444446078, 14155.716885662228, 5668.149777533403, 5780.682062486603, 5824.213731146326, 5879.794207202813, 5915.497182739516, 985.6156099121348, 1011.9848202276971, 1020.5152552537634, 1022.943738094412, 1041.5833666533297, 22723.204687836176, 22807.657885131735, 22929.426764331027, 23274.06903723832, 23296.534069318517, 1111.822108462642, 1118.6364431559775, 1123.4888125902694, 1124.3591153042687, 1133.9716507087223, 11910.166288359731, 12217.144799863925, 12227.082968777377, 12302.507899683913, 12327.0, 234996.0, 235782.0, 236010.09974750425, 236128.0, 236179.0, 50259.49740502548, 50291.74854125779, 50444.74777626088, 50444.74777626162, 50803.74598126986, 1427.1437137717287, 1478.3643033495484, 1479.6976918905236, 1481.6295926018495, 1484.606579256498, 22020.559588807813, 22169.889150554143, 22527.88736056309, 22587.322380328616, 22626.773732263126, 35505.0, 35541.0, 35607.82196089003, 35610.28779424449, 35647.82176089103, 194190.0, 194947.05052949855, 195167.0, 195524.0, 195775.0, 31523.21191970219, 32664.34671306513, 33257.168570784954, 33364.499532507514, 33415.83292083621, 40138.598614013485, 41575.7921210392, 41613.0, 41657.79171104246, 41785.58214417939, 30746.846265768527, 30864.228394290323, 31178.844105779324, 31187.53218701676, 31570.842145789124, 30596.235094122825, 30693.53959690562, 30807.69192308048, 30834.845825770728, 30910.53634195534, 34866.47700284447, 37488.812555937046, 38231.617683822806, 38258.234835303694, 38352.80823595864, 31585.0, 32518.83740581282, 32572.0, 32702.672973270914, 32833.83583082165, 23109.306720798402, 24005.759942400353, 24153.396165096012, 24501.754982449947, 24534.87732561326], "value": 16935.390836356833, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.6781802190774, 580.2080160580808, 583.2388732703821, 586.9677167755772, 590.1767034986194, 21433.57132857344, 22192.0, 22225.333240002827, 22277.220297289598, 22476.325710228677, 25941.610875836832, 26287.0, 26417.075402360922, 26461.338466538346, 26478.073267435644, 6421.550491465611, 6534.24856141545, 6572.5399222054475, 6573.1783527059115, 6585.045168450596, 6372.553921225516, 6496.837579060514, 6646.700898459557, 6978.0, 6999.475039372042, 7013.754518591851, 7018.22799492058, 7020.613866237355, 7023.61370124643, 7026.82432939178, 7008.36924676779, 7020.40326572242, 7022.262662420423, 7023.789286321418, 7024.754133605325, 6848.246692863781, 6853.760118395882, 6856.417204537571, 6856.41720453762, 6883.483738719566, 29775.851120744475, 30238.697613023807, 31766.364672706415, 32152.839235803905, 32201.51697724536, 1988.6917527783216, 2052.6100040992164, 2052.8460365472647, 2054.3528788431595, 2055.465578949484, 18772.249110035584, 19264.422067337862, 19354.516137096543, 19452.708209376728, 19491.220351185937, 2303.7120359955006, 2370.7747763962398, 2376.9049238030457, 2434.6713193718797, 2440.255722004788, 137528.31235843757, 141207.0, 141454.0, 141509.58490415066, 141635.29182354125, 20007.299744508873, 20067.799322006595, 21318.78681213199, 21635.459113522287, 22145.23877366374, 42730.0, 42749.78625106917, 42774.358384624255, 42956.78521607434, 43008.0, 11426.257293276076, 11490.310581365145, 11592.88407115935, 11686.47410866513, 11779.411029448493, 9661.613535458644, 9749.463779492055, 9776.853347199798, 9860.211183105333, 9878.407295562289, 9259.629614815332, 10959.0, 11261.774764504664, 11359.432028398713, 11426.542938282542, 3577.4454959481322, 3918.825052096114, 4045.7977101144825, 4080.5103387593085, 4094.1545625790386, 957.9808403831885, 961.8701475300885, 976.3019441099648, 980.9068138526828, 994.8756405449319, 19400.902995484932, 23616.05535778584, 24873.0, 24972.500549988898, 25050.24849254525, 1015.3755440404246, 1032.7211652853687, 1039.6309310194922, 1048.9895101049044, 1061.8991195836384, 11299.435028248554, 11617.593384231512, 11618.360990145455, 11646.76706465866, 11761.059115270758, 512910.0, 515712.0, 529545.0, 534945.0, 539831.0, 121284.39357803155, 121880.39059804645, 123174.0, 127902.0, 131195.34402327926, 1227.1532642476664, 1228.4042239513828, 1248.7252804383204, 1261.3188878005958, 1261.8864302212662, 17985.280588776826, 18030.459086227434, 18206.45380638583, 18270.360537381126, 18468.44594662162, 58775.4122458787, 59625.70187149037, 60438.39561604503, 60633.0, 60647.0, 72100.63949680218, 74510.0, 75112.24887751271, 75196.24803751892, 76734.61632691801, 12154.453049612965, 12461.0, 12889.548865789653, 14698.853011469748, 15072.397104115713, 23060.654090188997, 23103.306900793003, 23198.1880634177, 23264.0, 24369.39076523101, 16079.678406431573, 16182.838171618134, 16235.918820405823, 18387.0, 18456.907715461337, 15817.68364632678, 16934.830651693817, 17485.912570437577, 17893.673822403005, 18043.639127217644, 16653.750193747346, 17480.21339039721, 17578.560535986704, 17611.55971100733, 17872.642547149244, 14793.482228121964, 14917.776233356726, 14993.625159371102, 15034.849651503782, 15082.698346033238, 18772.906135469235, 18819.0, 18959.0, 19055.714164287823, 19181.136848842114], "value": 12823.527397732085, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.4201214483844, 1414.2575148047274, 1417.1284659934145, 1418.375914597577, 1430.2419717549697, 14846.331915063822, 15485.922570387147, 15498.535043948685, 15501.612459688513, 15755.212239388042, 15805.762913556306, 16104.19479026047, 16126.274317655705, 16131.274092665828, 16138.919305403457, 2829.165396208113, 2877.7410033097017, 2880.251134704971, 2890.2196406970106, 2891.34944637457, 2863.4702580022763, 2962.111366590025, 2967.1840243932875, 2968.465676178287, 2969.8218106913655, 2817.7745780337627, 2832.830030198195, 2840.318323602341, 2862.127051249368, 2879.064304101165, 2797.2027972028077, 2816.197383745633, 2827.8303301801957, 2829.6462942132234, 2840.3609187932757, 2740.8081434299606, 2744.5745909384077, 2754.0911499205213, 2771.514984877638, 2780.0, 14856.554303370916, 15230.695386092217, 15238.542843714595, 15252.618684532865, 15310.617234569114, 7791.961040194819, 8015.519068855829, 8016.318612917909, 8022.037355517317, 8025.357971362277, 5332.600054995852, 5549.306336707911, 5579.944200557983, 5636.887262254732, 5650.632708873951, 1946.7566554180728, 1949.8147675970902, 1953.8436925045962, 1980.9405717828488, 2005.3883565512513, 26729.19812405631, 26900.19299421001, 26907.596386054225, 26966.191014269603, 27006.5949010765, 6575.375339342851, 7006.159260888726, 7012.439004879598, 7109.182444019004, 7136.393406560397, 16105.67788644244, 16109.677806443806, 16202.432914847925, 16219.594510137105, 16261.430849920196, 7677.884831727529, 7740.303372696484, 7811.765647030598, 7831.903533505314, 7837.608119593998, 7015.649217539102, 7130.322619351154, 7249.96375018132, 7302.780916572511, 7331.230220826843, 5340.706261155596, 5498.432946610277, 5513.61404701675, 5536.612437129363, 5563.471470210324, 1473.5284708893266, 1488.1294442751112, 1494.394770118104, 1498.6178524476188, 1502.8121484814399, 896.9596368163318, 942.9481378524247, 944.6976967370375, 957.2294303086082, 961.6345788600287, 7995.880061799077, 8009.519428834288, 8097.676092956216, 8132.105468398471, 8136.145704700921, 728.6101935464501, 730.8720973829664, 733.9229380914927, 740.8370158565107, 746.1009483572283, 5275.498827611371, 5710.0, 5779.422057794187, 5799.797007104731, 5834.737436815353, 20734.688979665316, 21075.578488430147, 21952.121915123535, 21980.89009554942, 22018.229361972255, 21123.26068587592, 21242.150313987266, 21763.45591360197, 21781.891090544446, 21800.672989905164, 10449.268551201494, 10687.091597214168, 10745.247832651796, 10817.56729730802, 10836.0, 8346.165383461605, 8379.413441059067, 8380.20388063133, 8418.326533877398, 8423.915760842314, 17297.0, 18290.542736431697, 18295.359662411753, 18414.72377914359, 18718.812811871707, 15377.114427860697, 17642.206100725754, 19520.60958780845, 19775.307864224684, 19779.307724229584, 10162.949185254027, 10628.149748020294, 11252.0, 11257.268277561932, 11312.717182070184, 13794.862051379358, 13938.442462301395, 14056.578302650936, 14065.0, 14071.366788494337, 10999.450027498753, 11069.723256918642, 11369.147313951322, 11577.59478418285, 11588.47851846652, 10867.184961127788, 10959.945200273947, 11045.171612129283, 11272.774544509228, 11432.657020289405, 10410.635627752992, 10616.363018218932, 10635.574577017142, 10647.627333043616, 10677.0, 7376.771441669396, 7627.351675107456, 7634.50375725576, 8014.158513356014, 8017.919820801717, 7704.229577042251, 7721.227877212234, 7742.341900938483, 7763.767086987399, 7867.567283799446], "value": 6733.582693828925, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.2252597211336, 509.30988510568204, 532.2468706779906, 539.6303532080527, 540.8891177308651, 6332.620042797435, 6355.3962373574495, 6399.976003839375, 6403.231612206541, 6451.0, 6068.878622427549, 6163.322034576204, 6182.84542886427, 6284.883401372109, 6298.559100862942, 5090.516400941905, 5103.157978933472, 5111.591072714174, 5134.589232861381, 5137.922931156036, 5019.121653710605, 5038.470960549144, 5054.974725126369, 5090.490950904898, 5108.84673459795, 4879.46325904152, 4954.207326827708, 5011.922436676114, 5066.341375621182, 5078.49215078491, 5007.198848184291, 5026.195808670595, 5045.394552653705, 5067.949320506821, 5082.1360368737405, 4523.570260825217, 4535.455745310551, 4543.727376357396, 4570.954290457119, 4580.152671755703, 6221.377862213742, 6241.843953901143, 6243.157173781527, 6277.2153480814895, 6297.464715499188, 5759.769609215627, 5763.164341170549, 5787.536997040227, 5794.565407594448, 5831.271091113611, 6616.371444712745, 6634.93365066348, 6668.233153187397, 6710.7315707371645, 6721.294264102296, 4867.610591152699, 4873.0, 4924.926126108111, 4932.112219800434, 4946.975265123688, 6941.687624056879, 6959.0, 6999.985002174656, 7038.436925045984, 7049.224585295613, 5019.799208031711, 5021.2549141061345, 5035.521625445577, 5057.671251368649, 5074.2642316864585, 5083.694978301313, 5159.793608255628, 5163.586913046947, 5178.737395440849, 5182.0, 5814.651120932757, 5830.329512106077, 5840.8539786504825, 5841.065429531254, 5850.619709718854, 6711.328867113347, 6784.626845523445, 6785.9660701696175, 6816.659167041628, 6824.863502730017, 1683.6380178261732, 1711.7090094683933, 1717.957051073708, 1759.3754217252947, 1767.3549154558493, 1182.781185480689, 1231.095145068374, 1239.2192918461312, 1249.3066348176849, 1253.8432695913011, 5930.377310382434, 5936.7922122725495, 5939.851503712442, 5946.10808378738, 5950.761969521257, 6827.112475378269, 6832.437835811571, 6837.418819400307, 6846.760363387258, 6891.138607674041, 5898.351181370046, 5914.0, 5919.437653422918, 5927.318358388754, 5974.492168165754, 3586.6234045425376, 3615.204654975901, 3620.837062332201, 3626.546681664792, 3628.2017956050154, 6522.380373864476, 6545.541812073205, 6547.9672601636685, 6571.507136964651, 6593.5384523083885, 7001.7944347530365, 7020.227774944752, 7024.964875175694, 7044.696731104762, 7046.154461464657, 6580.177477815273, 6585.2426970898005, 6586.901196482057, 6631.933680663132, 6669.1330127083165, 6794.388505034571, 6822.556533825484, 6834.419074378535, 6835.624040677611, 6848.7260509579055, 6729.831754206184, 6732.427743641845, 6762.560433571802, 6787.524873258824, 6791.694373753094, 6615.470762338905, 6636.601803891782, 6649.501287403367, 6653.401193892573, 6670.266270710121, 5092.516210959953, 5098.923516147187, 5102.311187989722, 5108.48915108501, 5121.129408000724, 6808.455323574201, 6833.93166068333, 6883.27725588826, 6933.757318493828, 6965.512414131165, 6546.672666366758, 6575.178102737158, 6591.802245932629, 6615.636140012345, 6616.338366163538, 6060.484858787053, 6571.57284776488, 6595.614920866672, 6606.513534454709, 6650.068990341453, 6552.6723663815665, 6557.409833115043, 6572.967135164294, 6584.242812076673, 6595.340465953366, 6690.330966903465, 6696.866062678621, 6698.665066746546, 6702.664866756741, 6705.8264803658185, 6328.0824280478155, 6421.97707722066, 6423.261324947691, 6473.643949582818, 6474.935250647622], "value": 5138.406742243762, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [906.5875026862778, 913.4382354851768, 926.6154545863467, 930.1070971867007, 932.3892850183128, 18442.354517591888, 19220.327288544922, 19251.71122433161, 19257.614847703037, 21466.463338416546, 20004.199832006703, 21014.89492552539, 21282.0, 21299.787002129975, 21387.35837924861, 974.439697174126, 988.6391467114487, 993.3046867192958, 993.5181437003048, 994.060612720979, 949.8907625623073, 950.0, 950.8478643417054, 953.0326718380849, 1002.7743757654542, 938.8638647396158, 981.5190556627236, 986.8371718666448, 1040.8438734189895, 1061.925665203436, 973.2700474644017, 974.1476208317722, 975.1029053271008, 975.2052077556768, 1029.583018877352, 758.5297115788192, 759.3279947246726, 760.4182800157884, 761.1247065874234, 762.4624639629104, 17609.823901760945, 18895.527611809677, 20020.79979200204, 20294.391168264974, 20304.390868273975, 20223.696644550342, 20589.279375221795, 20915.477113072142, 21385.144594216214, 21409.785902140935, 16972.15139243033, 17042.82957170425, 17065.65868682632, 17104.059276739776, 17109.401170958958, 3760.8683696070507, 3799.4300854871713, 3800.20195758891, 3801.3157631626295, 3802.4676545283683, 126878.0, 127480.0, 131063.0, 131695.34152329274, 133104.33447832795, 13900.652483687989, 14019.439222431192, 14317.57047288583, 14415.711685766435, 14417.13497190172, 29868.850655747017, 30987.225319366746, 31580.052598422084, 31661.050168494523, 31698.524522132186, 7573.924260757432, 7774.1837107104075, 8137.715179968673, 8234.876476852853, 8250.092489826115, 3924.4113382992778, 4099.795010249476, 4103.158852435244, 4106.1992911382595, 4108.8356465741035, 5104.489551044866, 5184.403793563713, 5239.423663397023, 5251.737413129328, 5257.526822585985, 1065.3235195650818, 1082.4479515447179, 1083.0793825248477, 1086.9673909782719, 1087.494315143462, 682.5972676121112, 687.5737043033251, 689.3898899474002, 689.6172624193521, 709.4147328454046, 12043.27740335565, 12209.328486933126, 12231.204971676812, 12709.555165569162, 12871.292078935749, 699.8285420072115, 701.386286998876, 703.4196787650209, 720.4164626652429, 722.4978639845363, 5419.729013549307, 5441.401445840955, 6123.877522449615, 6213.502919766408, 6320.62076275425, 104100.47949760202, 104731.0, 106955.0, 108474.0, 114881.0, 94449.52775236218, 95006.0, 96209.51895240479, 97031.51484242534, 101230.49384753176, 79769.0, 81339.0, 82457.0, 90418.0, 90771.54614226887, 12021.639350819489, 12501.624951251477, 12538.373081346079, 12615.558455454015, 12968.0, 38947.0, 39736.60263397329, 42178.0, 42326.788366057976, 42402.78798605987, 52258.47741522536, 63179.36820631735, 73060.63469682618, 73381.6330918342, 73418.0, 17408.39070632522, 17505.912470437564, 17587.912060439616, 17744.733828992317, 17802.643947120727, 20427.0, 20452.488687782923, 20472.69290960607, 20525.17899284012, 21384.679229811252, 18552.350667726565, 19034.428967131007, 19042.52393690169, 19163.0, 19248.326308579133, 18348.0, 18813.341533046827, 18871.245150193838, 19009.619807604045, 19458.805411946265, 18362.540936476697, 18423.170957306684, 18733.625327493646, 18741.250349985847, 19081.332153374566, 13185.80221296662, 13446.260455674641, 13529.72940541164, 13631.977601679715, 13740.244286564335, 12558.937205313914, 12608.117431779687, 13293.135946163855, 13833.584992450242, 13927.860721392657], "value": 9119.537349099057, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1483.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 108.0, 95.0, 108.0, 108.0, 111.0, 108.0, 107.0, 109.0, 109.0], "value": 106.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 118.0, 144.0, 178.0, 115.0, 150.0, 139.0, 118.0, 169.0, 117.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 161.0, 163.0, 161.0, 160.0, 159.0, 162.0, 171.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 80.0, 173.0, 79.0, 74.0, 74.0, 73.0, 178.0, 74.0], "value": 95.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 133.0, 79.0, 79.0, 80.0, 132.0, 78.0, 78.0, 126.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 115.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 148.0, 148.0, 147.0, 149.0, 148.0, 148.0, 150.0, 149.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 52.0, 53.0, 54.0, 53.0, 55.0, 53.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 74.0, 77.0, 75.0, 79.0, 73.0, 75.0, 76.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 124.0, 123.0, 122.0, 119.0, 121.0, 122.0, 129.0, 116.0, 157.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 179.0, 172.0, 176.0, 171.0, 181.0, 166.0, 165.0, 167.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 63.0, 65.0, 65.0, 63.0, 64.0, 69.0], "value": 64.2, "unit": "score"}]}]} 03:05:01 INFO - Return code: 0 03:05:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:05:01 INFO - # TBPL SUCCESS # 03:05:01 INFO - Running post-action listener: _package_coverage_data 03:05:01 INFO - Running post-action listener: _resource_record_post_action 03:05:01 INFO - [mozharness: 2017-09-03 10:05:01.993863Z] Finished run-tests step (success) 03:05:01 INFO - Running post-run listener: _resource_record_post_run 03:05:02 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 7090176; Write bytes: 397660160; Read time: 3112; Write time: 256180 03:05:02 INFO - TinderboxPrint: CPU usage
13.1% 03:05:02 INFO - TinderboxPrint: I/O read bytes / time
7,090,176 / 3,112 03:05:02 INFO - TinderboxPrint: I/O write bytes / time
397,660,160 / 256,180 03:05:02 INFO - TinderboxPrint: CPU idle
8,257.9 (86.9%) 03:05:02 INFO - TinderboxPrint: CPU user
1,193.1 (12.6%) 03:05:02 INFO - TinderboxPrint: Swap in / out
0 / 0 03:05:02 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112312320; Read time: 0; Write time: 68808 03:05:02 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:05:02 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 5345280; Write bytes: 285347840; Read time: 3032; Write time: 187372 03:05:02 INFO - Running post-run listener: _upload_blobber_files 03:05:02 INFO - Blob upload gear active. 03:05:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:05:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:05:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:05:03 INFO - (blobuploader) - INFO - Open directory for files ... 03:05:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:05:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:05:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:04 INFO - (blobuploader) - INFO - Done attempting. 03:05:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:05:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:05:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:11 INFO - (blobuploader) - INFO - Done attempting. 03:05:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:05:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:05:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:12 INFO - (blobuploader) - INFO - Done attempting. 03:05:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:05:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:05:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:13 INFO - (blobuploader) - INFO - Done attempting. 03:05:13 INFO - (blobuploader) - INFO - Iteration through files over. 03:05:13 INFO - Return code: 0 03:05:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:05:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:05:13 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bdd70c187f44478acecfc750afe4dd3facf0715cbdf935341e08bfe359aceeddac7a28b4efe140282c117a45a009c1f6653cc2e3d4b904808c0fb61d44aa4201", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4924769b797e51d1b29962cb4b9a89ae441f9527eff2a65e0d93df6c6f27a71c937c91f809b433786fdd962ab469eaed5ed0f8fb009327992e85c1ef48864849", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f017456dab4d4cf8c93aeb01bcacbf1986404df48e51b5078fd6f9c6660ee0efd2378b7a3afc5358a06e5d9c0f4a3adb96a91b75e2c9aa0457d01d5488b2d0dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d311857c24c0ac1d7694ab330b015a87394d71421838b20a8502c307cfb1d998758236eac796bdc4c81b6ef9514b5cbb1b8d7fd450c3846ea3a11720a9da608d"} 03:05:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:05:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:05:13 INFO - Contents: 03:05:13 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bdd70c187f44478acecfc750afe4dd3facf0715cbdf935341e08bfe359aceeddac7a28b4efe140282c117a45a009c1f6653cc2e3d4b904808c0fb61d44aa4201", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4924769b797e51d1b29962cb4b9a89ae441f9527eff2a65e0d93df6c6f27a71c937c91f809b433786fdd962ab469eaed5ed0f8fb009327992e85c1ef48864849", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f017456dab4d4cf8c93aeb01bcacbf1986404df48e51b5078fd6f9c6660ee0efd2378b7a3afc5358a06e5d9c0f4a3adb96a91b75e2c9aa0457d01d5488b2d0dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d311857c24c0ac1d7694ab330b015a87394d71421838b20a8502c307cfb1d998758236eac796bdc4c81b6ef9514b5cbb1b8d7fd450c3846ea3a11720a9da608d"} 03:05:13 INFO - Running post-run listener: copy_logs_to_upload_dir 03:05:13 INFO - Copying logs to upload dir... 03:05:13 INFO - mkdir: /builds/slave/test/build/upload/logs 03:05:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.584669 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-09-03 03:05:13.145425) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 03:05:13.149618) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746 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/bdd70c187f44478acecfc750afe4dd3facf0715cbdf935341e08bfe359aceeddac7a28b4efe140282c117a45a009c1f6653cc2e3d4b904808c0fb61d44aa4201", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4924769b797e51d1b29962cb4b9a89ae441f9527eff2a65e0d93df6c6f27a71c937c91f809b433786fdd962ab469eaed5ed0f8fb009327992e85c1ef48864849", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f017456dab4d4cf8c93aeb01bcacbf1986404df48e51b5078fd6f9c6660ee0efd2378b7a3afc5358a06e5d9c0f4a3adb96a91b75e2c9aa0457d01d5488b2d0dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d311857c24c0ac1d7694ab330b015a87394d71421838b20a8502c307cfb1d998758236eac796bdc4c81b6ef9514b5cbb1b8d7fd450c3846ea3a11720a9da608d"} build_url:https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011239 build_url: 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bdd70c187f44478acecfc750afe4dd3facf0715cbdf935341e08bfe359aceeddac7a28b4efe140282c117a45a009c1f6653cc2e3d4b904808c0fb61d44aa4201", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4924769b797e51d1b29962cb4b9a89ae441f9527eff2a65e0d93df6c6f27a71c937c91f809b433786fdd962ab469eaed5ed0f8fb009327992e85c1ef48864849", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f017456dab4d4cf8c93aeb01bcacbf1986404df48e51b5078fd6f9c6660ee0efd2378b7a3afc5358a06e5d9c0f4a3adb96a91b75e2c9aa0457d01d5488b2d0dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d311857c24c0ac1d7694ab330b015a87394d71421838b20a8502c307cfb1d998758236eac796bdc4c81b6ef9514b5cbb1b8d7fd450c3846ea3a11720a9da608d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 03:05:13.182483) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 03:05:13.182780) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504426845.378063-1581998746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004391 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 03:05:13.261985) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 03:05:13.265302) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 03:05:13.265576) ========= ========= Total master_lag: 0.28 =========