builder: mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s slave: talos-linux64-ix-051 starttime: 1505506227.54 results: success (0) revision: 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 13:10:27.545109) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 13:10:27.545647) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 13:10:27.546078) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 13:10:27.563262) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:10:27.563778) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 13:10:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2017-09-15 13:10:27 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:10:27.703176) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:10:27.703476) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019827 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:10:27.755675) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 mins, 6 secs) (at 2017-09-15 13:10:27.756011) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 --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 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 --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=5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 13:10:28,071 truncating revision to first 12 chars 2017-09-15 13:10:28,071 Setting DEBUG logging. 2017-09-15 13:10:28,071 attempt 1/10 2017-09-15 13:10:28,071 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5b39f8dcc5cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 13:10:29,204 attempt 1/10 2017-09-15 13:10:29,745 current task status: no status available at this point. state: PENDING 2017-09-15 13:10:29,745 sleeping for 10.00s (attempt 1/10) 2017-09-15 13:10:39,755 attempt 2/10 2017-09-15 13:10:40,272 current task status: no status available at this point. state: PENDING 2017-09-15 13:10:40,272 sleeping for 14.00s (attempt 2/10) 2017-09-15 13:10:54,286 attempt 3/10 2017-09-15 13:10:54,828 current task status: no status available at this point. state: PENDING 2017-09-15 13:10:54,828 sleeping for 21.00s (attempt 3/10) 2017-09-15 13:11:15,850 attempt 4/10 2017-09-15 13:11:16,355 current task status: no status available at this point. state: PENDING 2017-09-15 13:11:16,355 sleeping for 30.50s (attempt 4/10) 2017-09-15 13:11:46,886 attempt 5/10 2017-09-15 13:11:47,405 current task status: no status available at this point. state: PENDING 2017-09-15 13:11:47,405 sleeping for 45.75s (attempt 5/10) 2017-09-15 13:12:33,201 attempt 6/10 2017-09-15 13:12:33,615 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-15 13:12:33,801 unpacking tar archive at: mozilla-central-5b39f8dcc5cd/testing/mozharness/ program finished with exit code 0 elapsedTime=126.076028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 mins, 6 secs) (at 2017-09-15 13:12:33.849208) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 13:12:33.849523) ========= script_repo_revision: 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 13:12:33.852899) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 13:12:33.853166) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 13:12:33.866640) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 11 secs) (at 2017-09-15 13:12:33.866952) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-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-stylo-disabled-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=5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:12:34 INFO - MultiFileLogger online at 20170915 13:12:34 in /builds/slave/test 13:12:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-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 13:12:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:12:34 INFO - {'append_to_log': False, 13:12:34 INFO - 'base_work_dir': '/builds/slave/test', 13:12:34 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 13:12:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:12:34 INFO - 'branch': 'Firefox-Non-PGO', 13:12:34 INFO - 'buildbot_json_path': 'buildprops.json', 13:12:34 INFO - 'code_coverage': False, 13:12:34 INFO - 'config_files': ('talos/linux_config.py',), 13:12:34 INFO - 'default_actions': ('clobber', 13:12:34 INFO - 'read-buildbot-config', 13:12:34 INFO - 'download-and-extract', 13:12:34 INFO - 'populate-webroot', 13:12:34 INFO - 'create-virtualenv', 13:12:34 INFO - 'install', 13:12:34 INFO - 'setup-mitmproxy', 13:12:34 INFO - 'run-tests'), 13:12:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:12:34 INFO - 'disable_ccov_upload': False, 13:12:34 INFO - 'disable_stylo': False, 13:12:34 INFO - 'download_minidump_stackwalk': True, 13:12:34 INFO - 'download_symbols': 'ondemand', 13:12:34 INFO - 'enable_stylo': False, 13:12:34 INFO - 'enable_webrender': False, 13:12:34 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 13:12:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:12:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:12:34 INFO - 'gecko_profile': False, 13:12:34 INFO - 'gecko_profile_interval': 0, 13:12:34 INFO - 'installer_path': 'installer.exe', 13:12:34 INFO - 'log_level': 'info', 13:12:34 INFO - 'log_name': 'talos', 13:12:34 INFO - 'log_to_console': True, 13:12:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:12:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:12:34 INFO - 'opt_config_files': (), 13:12:34 INFO - 'pip_index': False, 13:12:34 INFO - 'suite': 'dromaeojs-stylo-disabled-e10s', 13:12:34 INFO - 'system_bits': '32', 13:12:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:12:34 INFO - 'title': 'talos-linux64-ix-051', 13:12:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:12:34 INFO - 'use_talos_json': True, 13:12:34 INFO - 'verify': 'False', 13:12:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:12:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:12:34 INFO - 'work_dir': 'build'} 13:12:34 INFO - [mozharness: 2017-09-15 20:12:34.320330Z] Running clobber step. 13:12:34 INFO - Running pre-action listener: _resource_record_pre_action 13:12:34 INFO - Running main action method: clobber 13:12:34 INFO - rmtree: /builds/slave/test/build 13:12:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:12:35 INFO - Running post-action listener: _resource_record_post_action 13:12:35 INFO - [mozharness: 2017-09-15 20:12:35.332571Z] Finished clobber step (success) 13:12:35 INFO - [mozharness: 2017-09-15 20:12:35.332678Z] Running read-buildbot-config step. 13:12:35 INFO - Running pre-action listener: _resource_record_pre_action 13:12:35 INFO - Running main action method: read_buildbot_config 13:12:35 INFO - Using buildbot properties: 13:12:35 INFO - { 13:12:35 INFO - "project": "", 13:12:35 INFO - "product": "firefox", 13:12:35 INFO - "who": "archaeopteryx@coole-files.de", 13:12:35 INFO - "installer_path": "public/build/target.tar.bz2", 13:12:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s", 13:12:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 13:12:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-stylo-disabled-e10s", 13:12:35 INFO - "stage_platform": "linux64", 13:12:35 INFO - "basedir": "/builds/slave/test", 13:12:35 INFO - "buildnumber": 26, 13:12:35 INFO - "platform": "ubuntu64_hw", 13:12:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:12:35 INFO - "slavebuilddir": "test", 13:12:35 INFO - "taskId": "DEVNRO_iRgm_M1ADEPnPmQ", 13:12:35 INFO - "branch": "mozilla-central", 13:12:35 INFO - "script_repo_revision": "production", 13:12:35 INFO - "revision": "5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639", 13:12:35 INFO - "slavename": "talos-linux64-ix-051", 13:12:35 INFO - "repo_path": "mozilla-central" 13:12:35 INFO - } 13:12:35 INFO - Finding installer, test and symbols from parent task. 13:12:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DEVNRO_iRgm_M1ADEPnPmQ'}, attempt #1 13:12:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DEVNRO_iRgm_M1ADEPnPmQ'}, attempt #1 13:12:36 INFO - Task dependencies: Y6QodXyFSlC9NvyQ7Y-KNQ 13:12:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ'}, attempt #1 13:12:36 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.tar.bz2 13:12:36 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.test_packages.json 13:12:36 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.crashreporter-symbols.zip 13:12:36 INFO - Running post-action listener: _resource_record_post_action 13:12:36 INFO - [mozharness: 2017-09-15 20:12:36.786436Z] Finished read-buildbot-config step (success) 13:12:36 INFO - [mozharness: 2017-09-15 20:12:36.786660Z] Running download-and-extract step. 13:12:36 INFO - Running pre-action listener: _resource_record_pre_action 13:12:36 INFO - Running main action method: download_and_extract 13:12:36 INFO - mkdir: /builds/slave/test/build/tests 13:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:12:36 INFO - trying https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.test_packages.json 13:12:36 INFO - Downloading https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:12:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:12:37 INFO - Downloaded 989 bytes. 13:12:37 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:12:37 INFO - Using the following test package requirements: 13:12:37 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 13:12:37 INFO - u'common': [u'target.common.tests.zip'], 13:12:37 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:12:37 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:12:37 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:12:37 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:12:37 INFO - u'mozbase': [u'target.common.tests.zip'], 13:12:37 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:12:37 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:12:37 INFO - u'web-platform': [u'target.common.tests.zip', 13:12:37 INFO - u'target.web-platform.tests.tar.gz'], 13:12:37 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:12:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 13:12:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.common.tests.zip 13:12:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:12:37 INFO - Fetch https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.common.tests.zip into memory 13:12:40 INFO - Content-Length response header: 54734819 13:12:40 INFO - Bytes received: 54734819 13:12:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.talos.tests.zip 13:12:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:12:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.talos.tests.zip into memory 13:12:53 INFO - Content-Length response header: 14508749 13:12:53 INFO - Bytes received: 14508749 13:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:12:53 INFO - trying https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.tar.bz2 13:12:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:12:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:13:54 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.tar.bz2: The read operation timed out 13:13:54 INFO - retry: attempt #1 caught SSLError exception: The read operation timed out 13:13:54 INFO - retry: Failed, sleeping 30 seconds before retrying 13:14:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #2 13:14:27 INFO - Downloaded 61465141 bytes. 13:14:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.tar.bz2 13:14:27 INFO - mkdir: /builds/slave/test/properties 13:14:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:14:27 INFO - Writing to file /builds/slave/test/properties/build_url 13:14:27 INFO - Contents: 13:14:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.tar.bz2 13:14:27 INFO - Running post-action listener: _resource_record_post_action 13:14:27 INFO - Running post-action listener: find_tests_for_verification 13:14:27 INFO - Running post-action listener: set_extra_try_arguments 13:14:27 INFO - [mozharness: 2017-09-15 20:14:27.688083Z] Finished download-and-extract step (success) 13:14:27 INFO - [mozharness: 2017-09-15 20:14:27.688178Z] Running populate-webroot step. 13:14:27 INFO - Running pre-action listener: _resource_record_pre_action 13:14:27 INFO - Running main action method: populate_webroot 13:14:27 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:14:27 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['tresize', 13:14:27 INFO - 'tcanvasmark']}, 13:14:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:14:27 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['dromaeo_css', 13:14:27 INFO - 'kraken']}, 13:14:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:14:27 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:14:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'tests': ['damp', 'tps']}, 13:14:27 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['damp', 'tps']}, 13:14:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:14:27 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['dromaeo_dom']}, 13:14:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 13:14:27 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['basic_compositor_video', 13:14:27 INFO - 'glvideo']}, 13:14:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 13:14:27 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['ts_paint_webext', 13:14:27 INFO - 'tp5o_webext']}, 13:14:27 INFO - 'other-e10s': {'tests': ['a11yr', 13:14:27 INFO - 'ts_paint', 13:14:27 INFO - 'tpaint', 13:14:27 INFO - 'sessionrestore', 13:14:27 INFO - 'sessionrestore_many_windows', 13:14:27 INFO - 'sessionrestore_no_auto_restore', 13:14:27 INFO - 'tabpaint']}, 13:14:27 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['a11yr', 13:14:27 INFO - 'ts_paint', 13:14:27 INFO - 'tpaint', 13:14:27 INFO - 'sessionrestore', 13:14:27 INFO - 'sessionrestore_many_windows', 13:14:27 INFO - 'sessionrestore_no_auto_restore', 13:14:27 INFO - 'tabpaint']}, 13:14:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 13:14:27 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 13:14:27 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['perf_reftest_singletons']}, 13:14:27 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['bloom_basic']}, 13:14:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:14:27 INFO - 'tsvgr_opacity', 13:14:27 INFO - 'tart', 13:14:27 INFO - 'tscrollx', 13:14:27 INFO - 'tsvg_static']}, 13:14:27 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['tsvgx', 13:14:27 INFO - 'tsvgr_opacity', 13:14:27 INFO - 'tart', 13:14:27 INFO - 'tscrollx', 13:14:27 INFO - 'tsvg_static']}, 13:14:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:14:27 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--disable-stylo'], 13:14:27 INFO - 'tests': ['tp5o']}, 13:14:27 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:14:27 INFO - 'talos_options': ['--mitmproxy', 13:14:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:14:27 INFO - '--firstNonBlankPaint'], 13:14:27 INFO - 'tests': ['tp6_google', 13:14:27 INFO - 'tp6_youtube', 13:14:27 INFO - 'tp6_amazon', 13:14:27 INFO - 'tp6_facebook']}, 13:14:27 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:14:27 INFO - 'talos_options': ['--disable-stylo', 13:14:27 INFO - '--mitmproxy', 13:14:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:14:27 INFO - '--firstNonBlankPaint'], 13:14:27 INFO - 'tests': ['tp6_google', 13:14:27 INFO - 'tp6_youtube', 13:14:27 INFO - 'tp6_amazon', 13:14:27 INFO - 'tp6_facebook']}, 13:14:27 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:14:27 INFO - 'talos_options': ['--stylo-threads=1', 13:14:27 INFO - '--mitmproxy', 13:14:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:14:27 INFO - '--firstNonBlankPaint'], 13:14:27 INFO - 'tests': ['tp6_google', 13:14:27 INFO - 'tp6_youtube', 13:14:27 INFO - 'tp6_amazon', 13:14:27 INFO - 'tp6_facebook']}, 13:14:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--xperf_path', 13:14:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:14:27 INFO - 'tests': ['tp5n']}, 13:14:27 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--disable-stylo', 13:14:27 INFO - '--xperf_path', 13:14:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:14:27 INFO - 'tests': ['tp5n']}}} 13:14:27 INFO - Running post-action listener: _resource_record_post_action 13:14:27 INFO - [mozharness: 2017-09-15 20:14:27.703292Z] Finished populate-webroot step (success) 13:14:27 INFO - [mozharness: 2017-09-15 20:14:27.703381Z] Running create-virtualenv step. 13:14:27 INFO - Running pre-action listener: _resource_record_pre_action 13:14:27 INFO - Running main action method: create_virtualenv 13:14:27 INFO - Creating virtualenv /builds/slave/test/build/venv 13:14:27 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:14:27 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 13:14:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 13:14:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:14:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:14:28 INFO - Installing distribute.............................................................................................................................................................................................done. 13:14:28 INFO - Installing pip...............done. 13:14:28 INFO - Return code: 0 13:14:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:14:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:14:28 INFO - Reading from file tmpfile_stdout 13:14:28 INFO - Output received: 13:14:28 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 13:14:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:28 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 0x24781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2641a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', '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 13:14:28 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 13:14:28 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 13:14:28 INFO - Using env: {'DISPLAY': ':0', 13:14:28 INFO - 'HOME': '/home/cltbld', 13:14:28 INFO - 'LANG': 'en_US.UTF-8', 13:14:28 INFO - 'LANGUAGE': 'en_US:en', 13:14:28 INFO - 'LOGNAME': 'cltbld', 13:14:28 INFO - 'MAIL': '/var/mail/cltbld', 13:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:28 INFO - 'MOZ_NO_REMOTE': '1', 13:14:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:14:28 INFO - 'NO_EM_RESTART': '1', 13:14:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:28 INFO - 'PWD': '/builds/slave/test', 13:14:28 INFO - 'SHELL': '/bin/bash', 13:14:28 INFO - 'SHLVL': '1', 13:14:28 INFO - 'TERM': 'linux', 13:14:28 INFO - 'TMOUT': '86400', 13:14:28 INFO - 'USER': 'cltbld', 13:14:28 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:28 INFO - '_': '/tools/buildbot/bin/python'} 13:14:29 INFO - Ignoring indexes: http://pypi.python.org/simple/ 13:14:29 INFO - Downloading/unpacking pip>=1.5 13:14:29 INFO - Running setup.py egg_info for package pip 13:14:29 INFO - warning: no files found matching 'pip/cacert.pem' 13:14:29 INFO - warning: no files found matching '*.html' under directory 'docs' 13:14:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 13:14:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 13:14:29 INFO - Installing collected packages: pip 13:14:29 INFO - Found existing installation: pip 1.1 13:14:29 INFO - Uninstalling pip: 13:14:29 INFO - Successfully uninstalled pip 13:14:29 INFO - Running setup.py install for pip 13:14:29 INFO - warning: no files found matching 'pip/cacert.pem' 13:14:29 INFO - warning: no files found matching '*.html' under directory 'docs' 13:14:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 13:14:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 13:14:29 INFO - Installing pip script to /builds/slave/test/build/venv/bin 13:14:29 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 13:14:29 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 13:14:29 INFO - Successfully installed pip 13:14:29 INFO - Cleaning up... 13:14:29 INFO - Return code: 0 13:14:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:29 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 0x24781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2641a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', '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 13:14:29 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 13:14:29 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 13:14:29 INFO - Using env: {'DISPLAY': ':0', 13:14:29 INFO - 'HOME': '/home/cltbld', 13:14:29 INFO - 'LANG': 'en_US.UTF-8', 13:14:29 INFO - 'LANGUAGE': 'en_US:en', 13:14:29 INFO - 'LOGNAME': 'cltbld', 13:14:29 INFO - 'MAIL': '/var/mail/cltbld', 13:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:29 INFO - 'MOZ_NO_REMOTE': '1', 13:14:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:14:29 INFO - 'NO_EM_RESTART': '1', 13:14:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:29 INFO - 'PWD': '/builds/slave/test', 13:14:29 INFO - 'SHELL': '/bin/bash', 13:14:29 INFO - 'SHLVL': '1', 13:14:29 INFO - 'TERM': 'linux', 13:14:29 INFO - 'TMOUT': '86400', 13:14:29 INFO - 'USER': 'cltbld', 13:14:29 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:29 INFO - '_': '/tools/buildbot/bin/python'} 13:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:30 INFO - Downloading/unpacking psutil>=3.1.1 13:14:30 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 13:14:30 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 13:14:30 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 13:14:30 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 13:14:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:14:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:14:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:14:31 INFO - Installing collected packages: psutil 13:14:31 INFO - Running setup.py install for psutil 13:14:31 INFO - building 'psutil._psutil_linux' extension 13:14:31 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 13:14:31 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 13:14:31 INFO - building 'psutil._psutil_posix' extension 13:14:31 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 13:14:31 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 13:14:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:14:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:14:31 INFO - Successfully installed psutil 13:14:31 INFO - Cleaning up... 13:14:31 INFO - Return code: 0 13:14:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:14:31 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')]} 13:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:31 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')]} 13:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:31 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 0x24781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2641a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', '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 13:14:31 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 13:14:31 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 13:14:31 INFO - Using env: {'DISPLAY': ':0', 13:14:31 INFO - 'HOME': '/home/cltbld', 13:14:31 INFO - 'LANG': 'en_US.UTF-8', 13:14:31 INFO - 'LANGUAGE': 'en_US:en', 13:14:31 INFO - 'LOGNAME': 'cltbld', 13:14:31 INFO - 'MAIL': '/var/mail/cltbld', 13:14:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:31 INFO - 'MOZ_NO_REMOTE': '1', 13:14:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:14:31 INFO - 'NO_EM_RESTART': '1', 13:14:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:14:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:31 INFO - 'PWD': '/builds/slave/test', 13:14:31 INFO - 'SHELL': '/bin/bash', 13:14:31 INFO - 'SHLVL': '1', 13:14:31 INFO - 'TERM': 'linux', 13:14:31 INFO - 'TMOUT': '86400', 13:14:31 INFO - 'USER': 'cltbld', 13:14:31 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:14:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:31 INFO - '_': '/tools/buildbot/bin/python'} 13:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:14:32 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 13:14:32 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 13:14:32 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 13:14:32 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 13:14:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:14:33 INFO - Installing collected packages: mozsystemmonitor 13:14:33 INFO - Running setup.py install for mozsystemmonitor 13:14:33 INFO - Successfully installed mozsystemmonitor 13:14:33 INFO - Cleaning up... 13:14:33 INFO - Return code: 0 13:14:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:14:33 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')]} 13:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:33 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')]} 13:14:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:33 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 0x24781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2641a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', '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 13:14:33 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 13:14:33 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 13:14:33 INFO - Using env: {'DISPLAY': ':0', 13:14:33 INFO - 'HOME': '/home/cltbld', 13:14:33 INFO - 'LANG': 'en_US.UTF-8', 13:14:33 INFO - 'LANGUAGE': 'en_US:en', 13:14:33 INFO - 'LOGNAME': 'cltbld', 13:14:33 INFO - 'MAIL': '/var/mail/cltbld', 13:14:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:33 INFO - 'MOZ_NO_REMOTE': '1', 13:14:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:14:33 INFO - 'NO_EM_RESTART': '1', 13:14:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:14:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:33 INFO - 'PWD': '/builds/slave/test', 13:14:33 INFO - 'SHELL': '/bin/bash', 13:14:33 INFO - 'SHLVL': '1', 13:14:33 INFO - 'TERM': 'linux', 13:14:33 INFO - 'TMOUT': '86400', 13:14:33 INFO - 'USER': 'cltbld', 13:14:33 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:14:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:33 INFO - '_': '/tools/buildbot/bin/python'} 13:14:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:33 INFO - Downloading/unpacking jsonschema==2.5.1 13:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:14:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:34 INFO - Downloading functools32-3.2.3-2.tar.gz 13:14:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:14:34 INFO - warning: no files found matching '*.txt' 13:14:34 INFO - no previously-included directories found matching 'build' 13:14:34 INFO - no previously-included directories found matching 'dist' 13:14:34 INFO - no previously-included directories found matching '.git*' 13:14:34 INFO - Installing collected packages: jsonschema, functools32 13:14:34 INFO - Running setup.py install for functools32 13:14:34 INFO - warning: no files found matching '*.txt' 13:14:34 INFO - no previously-included directories found matching 'build' 13:14:34 INFO - no previously-included directories found matching 'dist' 13:14:34 INFO - no previously-included directories found matching '.git*' 13:14:35 INFO - Successfully installed jsonschema functools32 13:14:35 INFO - Cleaning up... 13:14:35 INFO - Return code: 0 13:14:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:14:35 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')]} 13:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:35 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')]} 13:14:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:35 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 0x24781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2641a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', '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 13:14:35 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 13:14:35 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 13:14:35 INFO - Using env: {'DISPLAY': ':0', 13:14:35 INFO - 'HOME': '/home/cltbld', 13:14:35 INFO - 'LANG': 'en_US.UTF-8', 13:14:35 INFO - 'LANGUAGE': 'en_US:en', 13:14:35 INFO - 'LOGNAME': 'cltbld', 13:14:35 INFO - 'MAIL': '/var/mail/cltbld', 13:14:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:35 INFO - 'MOZ_NO_REMOTE': '1', 13:14:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:14:35 INFO - 'NO_EM_RESTART': '1', 13:14:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:14:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:35 INFO - 'PWD': '/builds/slave/test', 13:14:35 INFO - 'SHELL': '/bin/bash', 13:14:35 INFO - 'SHLVL': '1', 13:14:35 INFO - 'TERM': 'linux', 13:14:35 INFO - 'TMOUT': '86400', 13:14:35 INFO - 'USER': 'cltbld', 13:14:35 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:14:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:35 INFO - '_': '/tools/buildbot/bin/python'} 13:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:14:35 INFO - Cleaning up... 13:14:35 INFO - Return code: 0 13:14:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:14:35 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')]} 13:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:35 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')]} 13:14:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:35 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 0x24781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2641a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', '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 13:14:35 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 13:14:35 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 13:14:35 INFO - Using env: {'DISPLAY': ':0', 13:14:35 INFO - 'HOME': '/home/cltbld', 13:14:35 INFO - 'LANG': 'en_US.UTF-8', 13:14:35 INFO - 'LANGUAGE': 'en_US:en', 13:14:35 INFO - 'LOGNAME': 'cltbld', 13:14:35 INFO - 'MAIL': '/var/mail/cltbld', 13:14:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:35 INFO - 'MOZ_NO_REMOTE': '1', 13:14:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:14:35 INFO - 'NO_EM_RESTART': '1', 13:14:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:14:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:35 INFO - 'PWD': '/builds/slave/test', 13:14:35 INFO - 'SHELL': '/bin/bash', 13:14:35 INFO - 'SHLVL': '1', 13:14:35 INFO - 'TERM': 'linux', 13:14:35 INFO - 'TMOUT': '86400', 13:14:35 INFO - 'USER': 'cltbld', 13:14:35 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:14:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:35 INFO - '_': '/tools/buildbot/bin/python'} 13:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:35 INFO - Downloading/unpacking blobuploader==1.2.4 13:14:35 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 13:14:35 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 13:14:35 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 13:14:35 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 13:14:36 INFO - Downloading blobuploader-1.2.4.tar.gz 13:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:14:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:14:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:37 INFO - Downloading docopt-0.6.1.tar.gz 13:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:14:37 INFO - Installing collected packages: blobuploader, requests, docopt 13:14:37 INFO - Running setup.py install for blobuploader 13:14:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:14:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:14:37 INFO - Running setup.py install for requests 13:14:37 INFO - Running setup.py install for docopt 13:14:37 INFO - Successfully installed blobuploader requests docopt 13:14:37 INFO - Cleaning up... 13:14:37 INFO - Return code: 0 13:14:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:37 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 0x24781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2641a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', '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 13:14:37 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 13:14:37 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 13:14:37 INFO - Using env: {'DISPLAY': ':0', 13:14:37 INFO - 'HOME': '/home/cltbld', 13:14:37 INFO - 'LANG': 'en_US.UTF-8', 13:14:37 INFO - 'LANGUAGE': 'en_US:en', 13:14:37 INFO - 'LOGNAME': 'cltbld', 13:14:37 INFO - 'MAIL': '/var/mail/cltbld', 13:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:37 INFO - 'MOZ_NO_REMOTE': '1', 13:14:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:14:37 INFO - 'NO_EM_RESTART': '1', 13:14:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:37 INFO - 'PWD': '/builds/slave/test', 13:14:37 INFO - 'SHELL': '/bin/bash', 13:14:37 INFO - 'SHLVL': '1', 13:14:37 INFO - 'TERM': 'linux', 13:14:37 INFO - 'TMOUT': '86400', 13:14:37 INFO - 'USER': 'cltbld', 13:14:37 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:38 INFO - '_': '/tools/buildbot/bin/python'} 13:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:14:38 INFO - Running setup.py (path:/tmp/pip-SXzSwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:14:38 INFO - Running setup.py (path:/tmp/pip-gbLHis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:14:38 INFO - Running setup.py (path:/tmp/pip-5ttlws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:14:38 INFO - Running setup.py (path:/tmp/pip-4Fz5Oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:14:38 INFO - Running setup.py (path:/tmp/pip-FnOsZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:14:38 INFO - Running setup.py (path:/tmp/pip-xlUmUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:14:38 INFO - Running setup.py (path:/tmp/pip-LB2XCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:14:38 INFO - Running setup.py (path:/tmp/pip-eEr8a7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:14:38 INFO - Running setup.py (path:/tmp/pip-w59FLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:14:38 INFO - Running setup.py (path:/tmp/pip-W5PUed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:14:38 INFO - Running setup.py (path:/tmp/pip-H7Gxvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:14:38 INFO - Running setup.py (path:/tmp/pip-RURHPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:14:39 INFO - Running setup.py (path:/tmp/pip-wMwBGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:14:39 INFO - Running setup.py (path:/tmp/pip-7RISs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:39 INFO - Running setup.py (path:/tmp/pip-5ntryI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:14:39 INFO - Running setup.py (path:/tmp/pip-BEVkUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:14:39 INFO - Running setup.py (path:/tmp/pip-RtlKo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:14:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:14:39 INFO - Running setup.py install for manifestparser 13:14:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:14:39 INFO - Running setup.py install for mozcrash 13:14:39 INFO - Running setup.py install for mozdebug 13:14:39 INFO - Running setup.py install for mozdevice 13:14:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:14:39 INFO - Running setup.py install for mozfile 13:14:39 INFO - Running setup.py install for mozhttpd 13:14:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Running setup.py install for mozinfo 13:14:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Running setup.py install for mozInstall 13:14:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Running setup.py install for mozleak 13:14:40 INFO - Running setup.py install for mozlog 13:14:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Running setup.py install for moznetwork 13:14:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Running setup.py install for mozprocess 13:14:40 INFO - Running setup.py install for mozprofile 13:14:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Running setup.py install for mozrunner 13:14:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:14:40 INFO - Running setup.py install for mozscreenshot 13:14:40 INFO - Running setup.py install for moztest 13:14:41 INFO - Running setup.py install for mozversion 13:14:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:14:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:14:41 INFO - Cleaning up... 13:14:41 INFO - Return code: 0 13:14:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14:41 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')]} 13:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:41 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')]} 13:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:41 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 0x24781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2641a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', '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 13:14:41 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 13:14:41 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 13:14:41 INFO - Using env: {'DISPLAY': ':0', 13:14:41 INFO - 'HOME': '/home/cltbld', 13:14:41 INFO - 'LANG': 'en_US.UTF-8', 13:14:41 INFO - 'LANGUAGE': 'en_US:en', 13:14:41 INFO - 'LOGNAME': 'cltbld', 13:14:41 INFO - 'MAIL': '/var/mail/cltbld', 13:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:41 INFO - 'MOZ_NO_REMOTE': '1', 13:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:14:41 INFO - 'NO_EM_RESTART': '1', 13:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:41 INFO - 'PWD': '/builds/slave/test', 13:14:41 INFO - 'SHELL': '/bin/bash', 13:14:41 INFO - 'SHLVL': '1', 13:14:41 INFO - 'TERM': 'linux', 13:14:41 INFO - 'TMOUT': '86400', 13:14:41 INFO - 'USER': 'cltbld', 13:14:41 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:41 INFO - '_': '/tools/buildbot/bin/python'} 13:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:14:41 INFO - Running setup.py (path:/tmp/pip-i6SXHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:14:41 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)) 13:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:14:41 INFO - Running setup.py (path:/tmp/pip-oSbgjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:14:41 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)) 13:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:14:41 INFO - Running setup.py (path:/tmp/pip-kUohKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:14:41 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)) 13:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:14:41 INFO - Running setup.py (path:/tmp/pip-bV79gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:14:41 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)) 13:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:14:41 INFO - Running setup.py (path:/tmp/pip-nuPnVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:14:41 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)) 13:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:14:41 INFO - Running setup.py (path:/tmp/pip-0NTutG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:14:41 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)) 13:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:14:41 INFO - Running setup.py (path:/tmp/pip-QlbE0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:14:41 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)) 13:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:14:41 INFO - Running setup.py (path:/tmp/pip-Mn71Yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:14:41 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)) 13:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:14:41 INFO - Running setup.py (path:/tmp/pip-xC8D9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:14:42 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)) 13:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:14:42 INFO - Running setup.py (path:/tmp/pip-CRZRfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:14:42 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)) 13:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:14:42 INFO - Running setup.py (path:/tmp/pip-lLGfmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:14:42 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)) 13:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:14:42 INFO - Running setup.py (path:/tmp/pip-i_jL6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:14:42 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)) 13:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:14:42 INFO - Running setup.py (path:/tmp/pip-TujGfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:14:42 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)) 13:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:14:42 INFO - Running setup.py (path:/tmp/pip-eoM5Gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:14:42 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)) 13:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:42 INFO - Running setup.py (path:/tmp/pip-8GIFGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:42 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)) 13:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:14:42 INFO - Running setup.py (path:/tmp/pip-XtU06g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:14:42 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)) 13:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:14:42 INFO - Running setup.py (path:/tmp/pip-iNM6X8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:14:42 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)) 13:14:42 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)) 13:14:42 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)) 13:14:42 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)) 13:14:42 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)) 13:14:42 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)) 13:14:42 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)) 13:14:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:14:42 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 13:14:42 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 13:14:42 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 13:14:42 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 13:14:43 INFO - Downloading blessings-1.6.tar.gz 13:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:14:43 INFO - Installing collected packages: blessings 13:14:43 INFO - Running setup.py install for blessings 13:14:43 INFO - Successfully installed blessings 13:14:43 INFO - Cleaning up... 13:14:43 INFO - Return code: 0 13:14:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:14:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:14:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:14:44 INFO - Reading from file tmpfile_stdout 13:14:44 INFO - Current package versions: 13:14:44 INFO - argparse == 1.2.1 13:14:44 INFO - blessings == 1.6 13:14:44 INFO - blobuploader == 1.2.4 13:14:44 INFO - docopt == 0.6.1 13:14:44 INFO - functools32 == 3.2.3-2 13:14:44 INFO - jsonschema == 2.5.1 13:14:44 INFO - manifestparser == 1.1 13:14:44 INFO - mozInstall == 1.13 13:14:44 INFO - mozcrash == 1.0 13:14:44 INFO - mozdebug == 0.1 13:14:44 INFO - mozdevice == 0.51 13:14:44 INFO - mozfile == 1.2 13:14:44 INFO - mozhttpd == 0.7 13:14:44 INFO - mozinfo == 0.10 13:14:44 INFO - mozleak == 0.1 13:14:44 INFO - mozlog == 3.5 13:14:44 INFO - moznetwork == 0.27 13:14:44 INFO - mozprocess == 0.25 13:14:44 INFO - mozprofile == 0.28 13:14:44 INFO - mozrunner == 6.13 13:14:44 INFO - mozscreenshot == 0.1 13:14:44 INFO - mozsystemmonitor == 0.3 13:14:44 INFO - moztest == 0.8 13:14:44 INFO - mozversion == 1.4 13:14:44 INFO - psutil == 3.1.1 13:14:44 INFO - requests == 1.2.3 13:14:44 INFO - wsgiref == 0.1.2 13:14:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14:44 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')]} 13:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:44 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')]} 13:14:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:44 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 0x24781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2641a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', '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 13:14:44 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 13:14:44 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 13:14:44 INFO - Using env: {'DISPLAY': ':0', 13:14:44 INFO - 'HOME': '/home/cltbld', 13:14:44 INFO - 'LANG': 'en_US.UTF-8', 13:14:44 INFO - 'LANGUAGE': 'en_US:en', 13:14:44 INFO - 'LOGNAME': 'cltbld', 13:14:44 INFO - 'MAIL': '/var/mail/cltbld', 13:14:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:44 INFO - 'MOZ_NO_REMOTE': '1', 13:14:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:14:44 INFO - 'NO_EM_RESTART': '1', 13:14:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:14:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:44 INFO - 'PWD': '/builds/slave/test', 13:14:44 INFO - 'SHELL': '/bin/bash', 13:14:44 INFO - 'SHLVL': '1', 13:14:44 INFO - 'TERM': 'linux', 13:14:44 INFO - 'TMOUT': '86400', 13:14:44 INFO - 'USER': 'cltbld', 13:14:44 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:14:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:44 INFO - '_': '/tools/buildbot/bin/python'} 13:14:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:44 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)) 13:14:44 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)) 13:14:44 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)) 13:14:44 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)) 13:14:44 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)) 13:14:44 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)) 13:14:44 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)) 13:14:44 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)) 13:14:44 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)) 13:14:44 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:14:44 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 13:14:44 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 13:14:44 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 13:14:44 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 13:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 13:14:45 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)) 13:14:45 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)) 13:14:45 INFO - Installing collected packages: simplejson 13:14:45 INFO - Running setup.py install for simplejson 13:14:45 INFO - building 'simplejson._speedups' extension 13:14:45 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 13:14:46 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 13:14:46 INFO - Successfully installed simplejson 13:14:46 INFO - Cleaning up... 13:14:46 INFO - Return code: 0 13:14:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:46 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 0x24781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2641a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', '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 13:14:46 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 13:14:46 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 13:14:46 INFO - Using env: {'DISPLAY': ':0', 13:14:46 INFO - 'HOME': '/home/cltbld', 13:14:46 INFO - 'LANG': 'en_US.UTF-8', 13:14:46 INFO - 'LANGUAGE': 'en_US:en', 13:14:46 INFO - 'LOGNAME': 'cltbld', 13:14:46 INFO - 'MAIL': '/var/mail/cltbld', 13:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:46 INFO - 'MOZ_NO_REMOTE': '1', 13:14:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:14:46 INFO - 'NO_EM_RESTART': '1', 13:14:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:46 INFO - 'PWD': '/builds/slave/test', 13:14:46 INFO - 'SHELL': '/bin/bash', 13:14:46 INFO - 'SHLVL': '1', 13:14:46 INFO - 'TERM': 'linux', 13:14:46 INFO - 'TMOUT': '86400', 13:14:46 INFO - 'USER': 'cltbld', 13:14:46 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:46 INFO - '_': '/tools/buildbot/bin/python'} 13:14:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:14:46 INFO - Cleaning up... 13:14:46 INFO - Return code: 0 13:14:46 INFO - Running post-action listener: _resource_record_post_action 13:14:46 INFO - Running post-action listener: _start_resource_monitoring 13:14:46 INFO - Starting resource monitoring. 13:14:46 INFO - [mozharness: 2017-09-15 20:14:46.865923Z] Finished create-virtualenv step (success) 13:14:46 INFO - [mozharness: 2017-09-15 20:14:46.866480Z] Running install step. 13:14:46 INFO - Running pre-action listener: _resource_record_pre_action 13:14:46 INFO - Running main action method: install 13:14:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:14:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:14:47 INFO - Reading from file tmpfile_stdout 13:14:47 INFO - Detecting whether we're running mozinstall >=1.0... 13:14:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:14:47 INFO - Reading from file tmpfile_stdout 13:14:47 INFO - Output received: 13:14:47 INFO - Usage: mozinstall [options] installer 13:14:47 INFO - Options: 13:14:47 INFO - -h, --help show this help message and exit 13:14:47 INFO - -d DEST, --destination=DEST 13:14:47 INFO - Directory to install application into. [default: 13:14:47 INFO - "/builds/slave/test"] 13:14:47 INFO - --app=APP Application being installed. [default: firefox] 13:14:47 INFO - mkdir: /builds/slave/test/build/application 13:14:47 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'] 13:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:15:04 INFO - Reading from file tmpfile_stdout 13:15:04 INFO - Output received: 13:15:04 INFO - /builds/slave/test/build/application/firefox/firefox 13:15:04 INFO - Running post-action listener: _resource_record_post_action 13:15:04 INFO - [mozharness: 2017-09-15 20:15:04.955947Z] Finished install step (success) 13:15:04 INFO - [mozharness: 2017-09-15 20:15:04.956197Z] Running setup-mitmproxy step. 13:15:04 INFO - Running pre-action listener: _resource_record_pre_action 13:15:04 INFO - Running main action method: setup_mitmproxy 13:15:04 INFO - Skipping: mitmproxy is not required 13:15:04 INFO - Running post-action listener: _resource_record_post_action 13:15:04 INFO - [mozharness: 2017-09-15 20:15:04.957343Z] Finished setup-mitmproxy step (success) 13:15:04 INFO - [mozharness: 2017-09-15 20:15:04.957565Z] Running run-tests step. 13:15:04 INFO - Running pre-action listener: _resource_record_pre_action 13:15:04 INFO - Running pre-action listener: _set_gcov_prefix 13:15:04 INFO - Running main action method: run_tests 13:15:04 WARNING - Try message not found. 13:15:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:15:04 INFO - Python 2.7.3 13:15:04 INFO - Return code: 0 13:15:04 INFO - grabbing minidump binary from tooltool 13:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:15:04 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 0x27e8090>, '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 0x27e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e2f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:15:04 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 13:15:04 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 13:15:04 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 13:15:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:15:05 INFO - Return code: 0 13:15:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:15:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:15:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:15:05 INFO - ENV: RUST_BACKTRACE is now full 13:15:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:15:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:15:05 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-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build 13:15:05 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-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-051 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log 13:15:05 INFO - Using env: {'DISPLAY': ':0', 13:15:05 INFO - 'HOME': '/home/cltbld', 13:15:05 INFO - 'LANG': 'en_US.UTF-8', 13:15:05 INFO - 'LANGUAGE': 'en_US:en', 13:15:05 INFO - 'LOGNAME': 'cltbld', 13:15:05 INFO - 'MAIL': '/var/mail/cltbld', 13:15:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:15:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:15:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:15:05 INFO - 'MOZ_NO_REMOTE': '1', 13:15:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:15:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:15:05 INFO - 'NO_EM_RESTART': '1', 13:15:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:15:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:15:05 INFO - 'PWD': '/builds/slave/test', 13:15:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:15:05 INFO - 'RUST_BACKTRACE': 'full', 13:15:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:15:05 INFO - 'SHELL': '/bin/bash', 13:15:05 INFO - 'SHLVL': '1', 13:15:05 INFO - 'TERM': 'linux', 13:15:05 INFO - 'TMOUT': '86400', 13:15:05 INFO - 'USER': 'cltbld', 13:15:05 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:15:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:15:05 INFO - '_': '/tools/buildbot/bin/python'} 13:15:05 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-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] with output_timeout 3600 13:15:05 INFO - mozversion application_buildid: 20170915192121 13:15:05 INFO - mozversion application_changeset: 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 13:15:05 INFO - mozversion application_display_name: Nightly 13:15:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:15:05 INFO - mozversion application_name: Firefox 13:15:05 INFO - mozversion application_remotingname: firefox 13:15:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:15:05 INFO - mozversion application_vendor: Mozilla 13:15:05 INFO - mozversion application_version: 57.0a1 13:15:05 INFO - mozversion platform_buildid: 20170915192121 13:15:05 INFO - mozversion platform_changeset: 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 13:15:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:15:05 INFO - mozversion platform_version: 57.0a1 13:15:05 INFO - using testdate: 1505506505 13:15:05 INFO - actual date: 1505506505 13:15:05 INFO - starting webserver on 'localhost:43749' 13:15:05 INFO - SUITE-START | Running 2 tests 13:15:05 INFO - TEST-START | dromaeo_css 13:15:05 INFO - Initialising browser for dromaeo_css test... 13:15:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43749/getInfo.html -profile /tmp/tmpnNYwsh/profile 13:15:05 INFO - TEST-INFO | started process 16532 (/builds/slave/test/build/application/firefox/firefox http://localhost:43749/getInfo.html) 13:15:10 INFO - TEST-INFO | 16532: exit 0 13:15:10 INFO - Browser initialized. 13:15:10 INFO - Running cycle 1/1 for dromaeo_css test... 13:15:10 INFO - Using env: {'DISPLAY': ':0', 13:15:10 INFO - 'HOME': '/home/cltbld', 13:15:10 INFO - 'JSGC_DISABLE_POISONING': '1', 13:15:10 INFO - 'LANG': 'en_US.UTF-8', 13:15:10 INFO - 'LANGUAGE': 'en_US:en', 13:15:10 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 13:15:10 INFO - 'LOGNAME': 'cltbld', 13:15:10 INFO - 'MAIL': '/var/mail/cltbld', 13:15:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:15:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:15:10 INFO - 'MOZ_CRASHREPORTER': '1', 13:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:15:10 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 13:15:10 INFO - 'MOZ_NO_REMOTE': '1', 13:15:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:15:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:15:10 INFO - 'NO_EM_RESTART': '1', 13:15:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:15:10 INFO - 'PWD': '/builds/slave/test', 13:15:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:15:10 INFO - 'RUST_BACKTRACE': 'full', 13:15:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:15:10 INFO - 'SHELL': '/bin/bash', 13:15:10 INFO - 'SHLVL': '1', 13:15:10 INFO - 'STYLO_FORCE_DISABLED': '1', 13:15:10 INFO - 'TERM': 'linux', 13:15:10 INFO - 'TMOUT': '86400', 13:15:10 INFO - 'USER': 'cltbld', 13:15:10 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:15:10 INFO - '_': '/tools/buildbot/bin/python'} 13:15:10 INFO - TEST-INFO | started process 16766 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnNYwsh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:15:11 INFO - PID 16766 | 13:15:11 INFO - PID 16766 | (/builds/slave/test/build/application/firefox/firefox:16816): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:15:11 INFO - PID 16766 | 13:15:21 INFO - PID 16766 | RSS: Main: 173662208 13:15:21 INFO - PID 16766 | 13:18:18 INFO - PID 16766 | Cycle 1(1): loaded http://localhost:43749/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43749/tests/dromaeo/cssquery-ext.html) 13:18:19 INFO - PID 16766 | RSS: Main: 176230400 13:18:19 INFO - PID 16766 | 13:21:20 INFO - PID 16766 | Cycle 1(1): loaded http://localhost:43749/tests/dromaeo/cssquery-ext.html (next: http://localhost:43749/tests/dromaeo/cssquery-jquery.html) 13:21:21 INFO - PID 16766 | RSS: Main: 172097536 13:21:21 INFO - PID 16766 | 13:24:23 INFO - PID 16766 | Cycle 1(1): loaded http://localhost:43749/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43749/tests/dromaeo/cssquery-mootools.html) 13:24:23 INFO - PID 16766 | RSS: Main: 172941312 13:24:23 INFO - PID 16766 | 13:27:25 INFO - PID 16766 | Cycle 1(1): loaded http://localhost:43749/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43749/tests/dromaeo/cssquery-prototype.html) 13:27:26 INFO - PID 16766 | RSS: Main: 173445120 13:27:26 INFO - PID 16766 | 13:30:29 INFO - PID 16766 | Cycle 1(1): loaded http://localhost:43749/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43749/tests/dromaeo/cssquery-yui.html) 13:30:30 INFO - PID 16766 | RSS: Main: 173035520 13:30:30 INFO - PID 16766 | 13:33:31 INFO - PID 16766 | Cycle 1(1): loaded http://localhost:43749/tests/dromaeo/cssquery-yui.html (next: http://localhost:43749/tests/dromaeo/cssquery-dojo.html) 13:33:31 INFO - PID 16766 | RSS: Main: 177516544 13:33:31 INFO - PID 16766 | 13:33:31 INFO - PID 16766 | __start_tp_report 13:33:31 INFO - PID 16766 | _x_x_mozilla_page_load 13:33:31 INFO - PID 16766 | _x_x_mozilla_page_load_details 13:33:31 INFO - PID 16766 | |i|pagename|runs| 13:33:31 INFO - PID 16766 | |0;dojo.html;3124.156477751007;3127.2338277122108;3128.436881361354;3151.732102771265;3177.5869137012187;6110.663913484767;6157.692115394229;6200;6254.874902501947;6311.747530098791;6094.390560943914;6159.076138579216;6207.844803879905;6213.254409470858;6221.191245138137;5719.914201286973;5777.277840269966;5805.535557155438;5805.680687562182;5811.535077193836;5782.9421705782825;5849.61977471463;5860.853478663046;5876.853078673024;5905.645661260338;61.8649782915234;5323.334583177122;5610.579206559504;5838.416158384149;5841.795537156222;5757.424257574251;5758.452946970031;5816.592838501307;5831.79588714393;5838.970805145947;5698.373178950312;5715.085586306171;5812.622179558315;5813.593048486608;5823.301203855522;6229.813105606839;6234.0960560718895;6258.24901011877;6266.843328916813;6330.113784070234;6670.299618540081;6691.065833056084;6803.217629972518;6806.897896531556;6810.046593476917;6639.468842492589;6740.056392105109;6743.5953842769595;6748.392644661955;6863.450923926124;6142.84642883927;6209.96895015522;6217.347178546278;6218.347073557302;6221.9422698141425;6753.223379311393;6873.381395674313;6874.484413668994;6878.449724022066;6884.277150899184;6611.438027767597;6612.669366531654;6695.096162018162;6704.899426508607;6706.865862682719;6590.9670451647435;6728.562643428161;6731.394174524316;6801.2178599461695;6804.11546498952;6612.801615951529;6620.668966551653;6752.729890804313;6756.2568117507035;6756.83107922296;6699.39705426514;6760.797176084725;6816.147981502312;6823.590584564942;6823.69293381799;5078.898422031612;5105.9489405106215;5173.741312934339;5174.275601415805;5182.067227898939;4465.330200469955;4483.484399294058;4525.321201819754;4545.409096817392;4580.335851301609;5829.708514574254;5841.561882858802;5903.291605007426;5904.645721256738;5906.143609176731;6748.426383757435;6751.797446076625;6828.180618325733;6840.486963477759;6843.213030501456;5573.052581061148;5643.971780141073;5669.603127781098;5780.046292361797;5857.062869940874;5695.288088988877;5721.885562288731;5742.942570574324;5790.42095790426;5793.8551536211935;6573.94816829305;6608.008798680236;6612.173478315211;6635;6643.40209381158;6487.610743355413;6570.408663220333;6615.106960560262;6645.734170633217;6749.122614060141;6537.542372033912;6654.13496245485;6655.334466553306;6656.800295991128;6657.7669781557415;6510.153680021567;6576.079348891255;6625.801225963229;6714.194296684233;6721.294264102395;6547.901781473377;6710.362515561014;6723.125993620798;6727.260001399744;6736.865262694817;6590.868182636224;6608.70260838273;6613.206415230203;6634;6647.767328143492;6519.152510173647;6523.217213934168;6598.802035938929;6600.60396376219;6607;6246.968765156145;6462.483001359974;6470.48236141117;6556.967215163893;6566.80299591013;6485.675716214076;6498.610083395011;6509.576877502947;6605.207375115017;6619.834504137435;6335.208098987627;6335.3981371769605;6431.48548116159;6456.160699109086;6466.676666166767 13:33:31 INFO - PID 16766 | |1;ext.html;810.2748040503749;827.1397746343803;828.3042244514606;830.410408609887;838.1785849867128;39554.208915821706;43050;43168.56831431685;43214.78392608037;43368.99999999996;38778.80610596943;39052;39142.21715565687;39211.60788392122;39226.21547569047;7005.124359455068;7097.290270972887;7139.143302803671;7805.180456052118;7973.202679732039;7928.167542408022;7987.600619969007;8021.478603890758;8032.236937490929;8033.4778239414545;7934.087579928296;7936.722214722486;7973.40199485038;7997.040355157389;8021;7859.135495095535;7902.565358905258;7957.403194760387;7985.001874765654;8003.1996800320085;7887.526748395057;7896.881546776803;7907.96046019772;7948.165442628499;7959.363250939911;37488.25023499515;37665;37777.62222377768;37847;38041;3607.4408466687705;3613.4579813027995;3632.037510059836;3638.9818050909576;3659.597444281127;31810.840945795124;31873.203169920707;32048.839755801306;32055.83972080148;32084.518732219036;5271.789128434858;5276.366835979707;5313.734313284359;5338.893222135574;5355.3305836770405;187721;188208;188865.05567472213;188943;189031;42259.15481690349;48237.31169409124;48552.5144748555;48984.265236021485;49185.26222106671;44812.55187448149;49447.50552494429;50343;50512.494875050776;50604.493955060716;16259.756103658454;16351;16451.753223701653;16465.91767041157;16515;13446.596602101952;13454.730905381837;13483.865161348458;13491.662708432368;13552.254625995665;13556.45774169042;13561.86438135606;13652;13746;13839.30803459843;5498.202760599691;5543.667379957215;5565.916511252334;5653.434656534396;5689.743961521742;1006.6376104602338;1011.3931641015479;1011.4184344002228;1025.8102251083574;1025.9692209233735;22546.21088261903;22643.43391415228;22690.319290421314;22726.09095636156;22935.54128917412;1086.2613422872364;1110.7889500994947;1113.6492005018392;1118.3960661242838;1119.9440027998567;12075.09436792244;12139.089568282237;12156.149069565046;12167.75664486723;12264.81602775959;223491;226942;227301;227596;228157;52144;52589.7370513145;52884.73557632187;52894.735526322125;53001.2049819253;1419.332913530639;1423.1532238318184;1428.0931608428514;1441.7188648213498;1444.978325325121;21896.562068758853;22106.668399974336;22308.665370019786;22540;22606.5478690422;33410.832945835115;36858.8157059213;36952.26095478021;36985.44521832228;37054.44418333781;194378;194896;195483;195619;196151;30225;30732.84633576818;32726;32836.34327313393;32849;42696.35955460608;42889.35665964951;42973.7851310754;43021.35467968046;43268;31833.522497163023;31963;32119.678803211667;32480.512792308607;33024.50463243005;31008.772586042876;31232.843835780677;31239.219019524695;31489.8425507871;31762.935409199254;35140;35453.290934181685;35462.645373545936;35502.04738739394;35625.46561801627;30829.22926926755;31072.53391199179;31213.687863121988;31603.525947111273;31771;22752.886235568716;23091;23350.59896406221;23660.408489787893;23669.053237870292 13:33:31 INFO - PID 16766 | |2;jquery.html;559.2310572962177;574.6552068758742;574.7126436781612;578.8379253808934;590.681032242589;21475.570488590238;22337.888310558465;22389.664155037688;22439.77560224397;22471.775282247174;23340.883295583542;24285.15001974931;24607.876960615216;24695.382615434624;24903.75096249037;6361.332060133689;6560.245571759261;6573.704183311763;6575.375339342755;6583.177102862142;6344.2386913570435;6441.8711625767455;6511.869762604745;6536.182977127859;6591.835204119888;6569.540132190748;6588.143541339619;6588.407043366096;6590.50571207159;6594.6043237405665;6341.8414539636415;6567.277599464055;6567.934320656804;6568.343165683417;6568.474522038226;6098.298695650013;6108.053251745987;6110.541709371771;6132.110843927606;6137.41694539018;29048.70951290481;29586.556201656993;29707;29779.95770148056;29830.40339193204;1994.1225860621425;2042.0606521000357;2043.7751847296786;2046.1099421751585;2049.661805802041;19103.140358683893;19453.513662158417;20075.09662065196;20133.39599812008;20206.696899546518;2451.2891261534132;2462.3597864555168;2503.2740505253455;2508.3478295643263;2526.7094284157374;114916;122645;126552;133356;142796.28601856926;14569.708605828037;14574.03550530592;14680.779788303184;14776.48282310114;14836.554903352917;23209.535809284058;23235.419114522272;23282.65076023861;23292.41768955756;23305.300840974458;10203.7449063774;10220.284580079471;10352.5341359639;10379.325343852626;10441.373517588969;9645.517724113766;9699.612015519442;9766.827980642369;9820;9896.356736812228;8877.378583499092;8989.640414383352;9246.630134794534;9268.397554158959;9332.72001839946;2555.5527782637964;2573.318070711263;2663.746944040313;2665.120510231619;2712.145674112648;925.546482223719;939.7133874168444;943.9952800236092;954.6038394066524;954.7756277274835;19044.71432928507;19116.90441547811;19308.613827723366;19459.708104378446;19650.90174549147;1014.4826138669332;1015.6292953072076;1020.7601213714771;1035.699647102347;1045.4041196518065;10667.359958402521;10739.516721747541;10860.67417977462;10944.56221751137;11037.227394082338;472072;474266;477672;484596;491749;115245;119947.40026299987;119957.40021299837;120706.3964680171;121598.39200803939;1106.5850306135198;1117.2961034548346;1149.2931846914087;1154.1343992005995;1155.3645494977898;17362.305507779547;17403.390881319094;17990.910045449687;18050.90974545119;18159.182836772114;53872;54753;55027;56060;57913;68194;68708;68732.31267687259;69263;69620.65189674222;14162.645933851736;14304;14517.782233266298;14521.128732276096;14643.56069317922;22625.09499619997;22701.772982269966;22955.311340659806;23098.653520196873;23277.185298514472;18008.549786254924;18106.818931810514;18240.635187295913;18261.81738182601;18550.44348669542;15956.202189890228;16663.50009499717;16788.160591970598;16935.491935241964;16964;17862.5534361642;17918.372856949944;17971.10144492744;18012.819871801115;18153.727694084864;14211.360488778237;14261.786073209118;14264.286785660883;14320.641983950069;14393.92803035978;17744.3789467368;17881.73177402314;17981.100944952963;18202.180901859185;18378.448646540626 13:33:31 INFO - PID 16766 | |3;mootools.html;1345.340783016322;1365.194535224218;1401.5164768154982;1407.8873690104797;1413.4840783114166;15130.319135638894;15799.210039498008;15807.999999999985;15810.920945395257;15811.972221805574;15405.998610090337;15437.073775573474;15503.612409689764;15517.456889008887;15587.532374028797;2766.1563223216913;2831.0940499040366;2857.4856525825344;2862.069827306134;2863.455943370763;2786.6656001279775;2807.1578526442086;2824.124521398373;2828.462592107493;2835.7022512636186;2681.9731802681918;2786.749192572668;2864.9713502865015;2865.699101594334;2866.2117917572627;2809.6909339972585;2858.628378310827;2880.323124065843;2883.207118042534;2884.9134525964255;2247.8538894971775;2351.000824649534;2728.399752054545;2755.3800394911186;2791.9302017449522;15199.924000380039;15246;15278.923605382013;15321.770173447296;15323.923380383138;8061.63722632477;8365.414420990533;8367.414281000332;8368.456050356708;8370.790730231733;5460.76108795392;5613.2422123013275;5699.202111704365;5795.275590551181;5807.332156801979;1940.4760714607119;1960.9313674021485;1970.4975231315923;2014.4057503036493;2014.5567975045467;26733.331666708295;27059.999999999804;27152.45695086107;27187;27252;7439.628018599048;7472.40220782347;7679.616019199129;7760.495567788189;7774.222577742181;17415.651686966445;17480.650386992187;17486.213120409357;17486.91256543709;17636.20637071309;8052.235037671412;8226.05400378952;8228.42401031934;8229.25936665703;8230;7451.701931922662;7521.194913220897;7546.547207167588;7553.622318884034;7570.432217583703;5001.349554646958;5177.456367081478;5266.868328291824;5281.815136470282;5350.27771250884;1546.883983701227;1557.7896983907253;1557.797486326793;1561.2662048837028;1562.1174036669245;835.2065537739172;874.2656168818223;879.4679219072369;881.7442941547008;912.8813254277035;7198.244184360567;7263.92736072643;7290.161631412456;7290.198078211392;7304.561726296439;697.7906628011576;706.8974998625171;711.0933559711306;712.6116266634697;713.1584730018628;4294.935575966363;4946.802127914915;5296.576273898079;5439.755211015514;5643.153526970905;21035.368938931857;22140.2250921217;22237.777622223894;22260.88869555642;22421.775782242294;21249.468763280733;21921.232756853435;22059.558808824055;22119.889400552893;22329.776702233095;10934.781304373868;10969.067629251444;10984.06635435981;11068.169887258488;11086.279391839505;8305.584720764058;8537.274331681876;8550.615222314886;8703.564821759013;8754.124587541195;18396.356127535473;18657;18868.05659717036;18920.716189257448;19236.519087022938;19298.13158407847;19661;19770.60458790845;19993.700094498887;20050.699239511127;10931.344119352865;10979.28634638746;11129.94265544772;11134.944325278646;11203.551857925917;13883.65290867696;14135.43458261658;14151.221682807545;14157.36291866848;14161.787573186617;11516.366599837089;11584.652460426201;11779.234349767237;11840.407979600814;11943.402829858298;11251.268667536584;11304.321740695585;11501.367424791717;11519.366434846164;11582;9815.460149691835;9885.15976142036;9900.653477128266;9902.752431189278;9910.256730745079;7436.330730234088;7500.362469189961;7511;7525.172231054689;7671.810869315265;7570.16728159913;7646.311831935152;7664.923350766644;7703.191164927826;7783.143854176149 13:33:31 INFO - PID 16766 | |4;prototype.html;480.1812908990172;502.7406347100514;504.4526688542928;509.9974500127499;531.285421108609;6062.181605483259;6149.907751383733;6177.876442471148;6191.566590338678;6278.37216278373;6107.633541987473;6115.602485838417;6116.204893363856;6124.356942521038;6125.9387406125925;5007.4992500749995;5049.4193167785625;5061.670991385567;5117.7185254810975;5122.41205226381;4821.132196204681;5043.722595257259;5045.369328833895;5091.592672586203;5107.69353838769;4864.975675121619;4898.926516102261;4906.6565340426005;4924.581410580105;4946.752662366867;4915.3364295819965;4918.5573298403315;4925.458199598059;4974.850754477371;4978.128827455217;4418.1384629997165;4420.535843736426;4430.357598148283;4446.444194475691;4469.642428605704;6082.178905847698;6100.908486372708;6143.508519318444;6154.5384096192965;6161.476274516672;5585.162225666142;5634.211210430543;5659.830205093854;5664.660120392789;5738.684372359518;6386;6449.12936753537;6474.446132928063;6477.967610161966;6485.7405703772265;4553.886152846172;4782.760861956888;4819.5180481951875;4821.325014497973;4855.757212139414;6629.303923088055;6633.6019838809825;6749.12261406019;6849.863002739918;6853.828654283633;4913.1893237616105;4913.263010548446;4943.2585112233455;4950.2079667253065;4954.727489988013;5036.974815125901;5043.773030213649;5043.974780126149;5066.544011039025;5083.669561478492;5583.553315734732;5604.411536788579;5630.18362337467;5638.464345887134;5639.6334238274385;6506.41442270198;6514.706838192198;6556.27880933097;6560.343965603402;6564.277929427759;1734.7918249810104;1736.905749377884;1742.817004214539;1746.3363921709922;1754.9385771498191;1154.365099195456;1200.9459574319176;1206.288289908958;1208.64949164743;1221.9816702749465;5668.716564171775;5693.857653558694;5707.543396528268;5728.770849165987;5749.91375129365;6535.346465353522;6572.178477690289;6574;6601.570897891622;6633.668316584151;5625.184348269478;5627.240322556484;5634.267545219177;5693.66198943248;5695.433755717128;3504.56312911705;3505.211327451303;3505.5267538882435;3526.982365088158;3531.29374125176;6519.706613202417;6522.054302126166;6545.345465453512;6591.835204119935;6601.933980660228;6779.457643388616;6802.65986700663;6809.489288303298;6821.372367796587;6849.726010959505;6120.571559990847;6428.200103970896;6488.188976377953;6505.934940650533;6562.8359291018105;6541.34586541342;6587.122670039016;6650.301718319507;6661.800145995628;6689.966550167218;6478.805635830932;6597.505187111082;6601.174853143357;6611.636360000246;6650.600963941985;6387.891995641335;6465.288818230085;6494.188226471691;6523.836904077246;6540.084388185753;4891.315215869852;4901.607871370211;4904.705717656952;4921.212605983025;4924.359833221658;6258.280297765815;6420.935790642221;6481.5138864584405;6566.770163044271;6692.230393504809;6272.623642581277;6367.777127800505;6422.807315780534;6459.547831651739;6465.838354040999;6348.428641422294;6348.619082855043;6368;6427.228732552124;6461.418472337325;6336.619802811846;6353;6425.1968503937005;6434.806955791334;6456.806295811133;6518.57629254097;6545.934540654533;6551.50863685216;6559.7376104955265;6591.868162636624;6233.719482623203;6234.220722409699;6307.96846015767;6310.2743184534365;6390.4248617624635 13:33:31 INFO - PID 16766 | |5;yui.html;909.0409617853165;910.544727636182;926.5367316341827;927.8979312275649;928.5125309212667;18297.63404731906;19431.611367772635;19431.902840485796;19441.805581944176;20569.588608227827;22058.227962021334;22150.113995440202;22184.88907555464;22250.332490025285;22251.666225006637;960.5149399553216;978.3200675530509;981.4160574458186;982.670805280232;984.6996666016862;726.2156870579788;744.6462930108182;947.8199142163002;989.9257555683316;996.2229461020415;1034.4982683398546;1043.6973277749444;1044.0290529807276;1044.5038606661851;1044.8798388185342;1028.059325716968;1035.5495359518595;1036.0468369100408;1036.6319956415514;1040.6877936618985;757.5833291689604;758.0524344569288;761.8476304739074;764.170874601053;767.4781148818856;17656.293748250053;18556.721649175273;18837.24651013958;18974.241030358768;18985.90507047456;21125.366239012856;21319.68020479694;21596.24413145548;21632.459188520257;21637.675434868488;17413.82586174135;17565.385211517536;17588.91205543977;17595.208215630326;17624.118794060247;3718.5537735471644;3726.9440958385644;3730.6642402183934;3733.6826369758605;3739;137034;137663.31168344093;138548.61451385458;139462.30268848693;139519;16181.352745890268;16662.916910400785;16670.66658666844;16706.331746730237;16738.414155504743;30831.383372332428;31804.522932156036;31935.201619959687;31940;32043;8319.376046796515;8573.74278771638;8608;8692.869606955901;8725.91274087251;4139.689523285705;4156.085661154541;4162.209180255742;4202.978985105055;4216.3464662977285;4936.703797772145;5151.8196863109615;5309.203619457112;5952.642841429441;6192.102145188923;1121.3440137519478;1150.9942450287695;1158.1082566423806;1158.9420528973685;1164.9068074554016;671.214678825775;674.3896773420063;684.1687349869932;693.389816961071;700.0514303119302;11727.179097463095;12502.74994500105;12585.874141258653;13148.474061037643;13374.732505349839;644.6100109433819;645.9354064593597;652.3248437866785;657.3853447027045;668.2115104177118;5802.274715660543;5813.389594092644;5816.883662326814;5848.355648639957;5916.585838991229;123627;123837;124452;124893;128550;108317;108601;109120.45439772909;110857;113505.43247283711;97599;97673;99362;99738;100328.49835750775;10969.122470202205;11060.778784424105;11062.834057489305;11142;11199.66401007971;45371.319430207914;45998.54001460077;46819;47313;47933.76033119812;57786.422135778106;57800;58645.70677146587;58722;61091;15771.68456630838;15795.289211985259;15855.365785368918;15861.841381586037;15879.68240635204;17551.1224438775;17777.911110444365;17930.641387171923;17931.462056138338;17984.550386239924;14197.929010354883;14285.857131429253;14344;14366.281685915872;14369;16511.669766604842;16522.09128497944;16614.086225257728;16749.162541872614;16795.664086718443;16067.437639682556;16162;16274.349026038826;16360.181990900646;16397.180140992663;12153.088518361335;12663.65103720379;12676.23942563449;12789.552365667529;12811;12814;12849.87150128524;12958;12991.090623656253;13072 13:33:31 INFO - PID 16766 | __end_tp_report 13:33:31 INFO - PID 16766 | __start_cc_report 13:33:31 INFO - PID 16766 | _x_x_mozilla_cycle_collect,355 13:33:31 INFO - PID 16766 | __end_cc_report 13:33:31 INFO - PID 16766 | __startTimestamp1505507611950__endTimestamp 13:33:31 INFO - PID 16766 | 13:33:31 INFO - PID 16766 | ------- Summary: start ------- 13:33:31 INFO - PID 16766 | Number of tests: 6 13:33:31 INFO - PID 16766 | 13:33:31 INFO - PID 16766 | [#0] dojo.html Cycles:165 Average:6137.97 Median:6470.48 stddev:894.69 (13.8%) stddev-sans-first:865.63 13:33:31 INFO - PID 16766 | Values: 3124.2 3127.2 3128.4 3151.7 3177.6 6110.7 6157.7 6200.0 6254.9 6311.7 6094.4 6159.1 6207.8 6213.3 6221.2 5719.9 5777.3 5805.5 5805.7 5811.5 5782.9 5849.6 5860.9 5876.9 5905.6 61.9 5323.3 5610.6 5838.4 5841.8 5757.4 5758.5 5816.6 5831.8 5839.0 5698.4 5715.1 5812.6 5813.6 5823.3 6229.8 6234.1 6258.2 6266.8 6330.1 6670.3 6691.1 6803.2 6806.9 6810.0 6639.5 6740.1 6743.6 6748.4 6863.5 6142.8 6210.0 6217.3 6218.3 6221.9 6753.2 6873.4 6874.5 6878.4 6884.3 6611.4 6612.7 6695.1 6704.9 6706.9 6591.0 6728.6 6731.4 6801.2 6804.1 6612.8 6620.7 6752.7 6756.3 6756.8 6699.4 6760.8 6816.1 6823.6 6823.7 5078.9 5105.9 5173.7 5174.3 5182.1 4465.3 4483.5 4525.3 4545.4 4580.3 5829.7 5841.6 5903.3 5904.6 5906.1 6748.4 6751.8 6828.2 6840.5 6843.2 5573.1 5644.0 5669.6 5780.0 5857.1 5695.3 5721.9 5742.9 5790.4 5793.9 6573.9 6608.0 6612.2 6635.0 6643.4 6487.6 6570.4 6615.1 6645.7 6749.1 6537.5 6654.1 6655.3 6656.8 6657.8 6510.2 6576.1 6625.8 6714.2 6721.3 6547.9 6710.4 6723.1 6727.3 6736.9 6590.9 6608.7 6613.2 6634.0 6647.8 6519.2 6523.2 6598.8 6600.6 6607.0 6247.0 6462.5 6470.5 6557.0 6566.8 6485.7 6498.6 6509.6 6605.2 6619.8 6335.2 6335.4 6431.5 6456.2 6466.7 13:33:31 INFO - PID 16766 | 13:33:31 INFO - PID 16766 | [#1] ext.html Cycles:180 Average:37077.39 Median:34275.42 stddev:52853.51 (154.2%) stddev-sans-first:52931.62 13:33:31 INFO - PID 16766 | Values: 810.3 827.1 828.3 830.4 838.2 39554.2 43050.0 43168.6 43214.8 43369.0 38778.8 39052.0 39142.2 39211.6 39226.2 7005.1 7097.3 7139.1 7805.2 7973.2 7928.2 7987.6 8021.5 8032.2 8033.5 7934.1 7936.7 7973.4 7997.0 8021.0 7859.1 7902.6 7957.4 7985.0 8003.2 7887.5 7896.9 7908.0 7948.2 7959.4 37488.3 37665.0 37777.6 37847.0 38041.0 3607.4 3613.5 3632.0 3639.0 3659.6 31810.8 31873.2 32048.8 32055.8 32084.5 5271.8 5276.4 5313.7 5338.9 5355.3 187721.0 188208.0 188865.1 188943.0 189031.0 42259.2 48237.3 48552.5 48984.3 49185.3 44812.6 49447.5 50343.0 50512.5 50604.5 16259.8 16351.0 16451.8 16465.9 16515.0 13446.6 13454.7 13483.9 13491.7 13552.3 13556.5 13561.9 13652.0 13746.0 13839.3 5498.2 5543.7 5565.9 5653.4 5689.7 1006.6 1011.4 1011.4 1025.8 1026.0 22546.2 22643.4 22690.3 22726.1 22935.5 1086.3 1110.8 1113.6 1118.4 1119.9 12075.1 12139.1 12156.1 12167.8 12264.8 223491.0 226942.0 227301.0 227596.0 228157.0 52144.0 52589.7 52884.7 52894.7 53001.2 1419.3 1423.2 1428.1 1441.7 1445.0 21896.6 22106.7 22308.7 22540.0 22606.5 33410.8 36858.8 36952.3 36985.4 37054.4 194378.0 194896.0 195483.0 195619.0 196151.0 30225.0 30732.8 32726.0 32836.3 32849.0 42696.4 42889.4 42973.8 43021.4 43268.0 31833.5 31963.0 32119.7 32480.5 33024.5 31008.8 31232.8 31239.2 31489.8 31762.9 35140.0 35453.3 35462.6 35502.0 35625.5 30829.2 31072.5 31213.7 31603.5 31771.0 22752.9 23091.0 23350.6 23660.4 23669.1 13:33:31 INFO - PID 16766 | 13:33:31 INFO - PID 16766 | [#2] jquery.html Cycles:180 Average:34133.62 Median:22663.43 stddev:80814.99 (356.6%) stddev-sans-first:81002.38 13:33:31 INFO - PID 16766 | Values: 559.2 574.7 574.7 578.8 590.7 21475.6 22337.9 22389.7 22439.8 22471.8 23340.9 24285.2 24607.9 24695.4 24903.8 6361.3 6560.2 6573.7 6575.4 6583.2 6344.2 6441.9 6511.9 6536.2 6591.8 6569.5 6588.1 6588.4 6590.5 6594.6 6341.8 6567.3 6567.9 6568.3 6568.5 6098.3 6108.1 6110.5 6132.1 6137.4 29048.7 29586.6 29707.0 29780.0 29830.4 1994.1 2042.1 2043.8 2046.1 2049.7 19103.1 19453.5 20075.1 20133.4 20206.7 2451.3 2462.4 2503.3 2508.3 2526.7 114916.0 122645.0 126552.0 133356.0 142796.3 14569.7 14574.0 14680.8 14776.5 14836.6 23209.5 23235.4 23282.7 23292.4 23305.3 10203.7 10220.3 10352.5 10379.3 10441.4 9645.5 9699.6 9766.8 9820.0 9896.4 8877.4 8989.6 9246.6 9268.4 9332.7 2555.6 2573.3 2663.7 2665.1 2712.1 925.5 939.7 944.0 954.6 954.8 19044.7 19116.9 19308.6 19459.7 19650.9 1014.5 1015.6 1020.8 1035.7 1045.4 10667.4 10739.5 10860.7 10944.6 11037.2 472072.0 474266.0 477672.0 484596.0 491749.0 115245.0 119947.4 119957.4 120706.4 121598.4 1106.6 1117.3 1149.3 1154.1 1155.4 17362.3 17403.4 17990.9 18050.9 18159.2 53872.0 54753.0 55027.0 56060.0 57913.0 68194.0 68708.0 68732.3 69263.0 69620.7 14162.6 14304.0 14517.8 14521.1 14643.6 22625.1 22701.8 22955.3 23098.7 23277.2 18008.5 18106.8 18240.6 18261.8 18550.4 15956.2 16663.5 16788.2 16935.5 16964.0 17862.6 17918.4 17971.1 18012.8 18153.7 14211.4 14261.8 14264.3 14320.6 14393.9 17744.4 17881.7 17981.1 18202.2 18378.4 13:33:31 INFO - PID 16766 | 13:33:31 INFO - PID 16766 | [#3] mootools.html Cycles:180 Average:9497.05 Median:2776.41 stddev:6805.63 (245.1%) stddev-sans-first:6797.16 13:33:31 INFO - PID 16766 | Values: 1345.3 1365.2 1401.5 1407.9 1413.5 15130.3 15799.2 15808.0 15810.9 15812.0 15406.0 15437.1 15503.6 15517.5 15587.5 2766.2 2831.1 2857.5 2862.1 2863.5 2786.7 2807.2 2824.1 2828.5 2835.7 2682.0 2786.7 2865.0 2865.7 2866.2 2809.7 2858.6 2880.3 2883.2 2884.9 2247.9 2351.0 2728.4 2755.4 2791.9 15199.9 15246.0 15278.9 15321.8 15323.9 8061.6 8365.4 8367.4 8368.5 8370.8 5460.8 5613.2 5699.2 5795.3 5807.3 1940.5 1960.9 1970.5 2014.4 2014.6 26733.3 27060.0 27152.5 27187.0 27252.0 7439.6 7472.4 7679.6 7760.5 7774.2 17415.7 17480.7 17486.2 17486.9 17636.2 8052.2 8226.1 8228.4 8229.3 8230.0 7451.7 7521.2 7546.5 7553.6 7570.4 5001.3 5177.5 5266.9 5281.8 5350.3 1546.9 1557.8 1557.8 1561.3 1562.1 835.2 874.3 879.5 881.7 912.9 7198.2 7263.9 7290.2 7290.2 7304.6 697.8 706.9 711.1 712.6 713.2 4294.9 4946.8 5296.6 5439.8 5643.2 21035.4 22140.2 22237.8 22260.9 22421.8 21249.5 21921.2 22059.6 22119.9 22329.8 10934.8 10969.1 10984.1 11068.2 11086.3 8305.6 8537.3 8550.6 8703.6 8754.1 18396.4 18657.0 18868.1 18920.7 19236.5 19298.1 19661.0 19770.6 19993.7 20050.7 10931.3 10979.3 11129.9 11134.9 11203.6 13883.7 14135.4 14151.2 14157.4 14161.8 11516.4 11584.7 11779.2 11840.4 11943.4 11251.3 11304.3 11501.4 11519.4 11582.0 9815.5 9885.2 9900.7 9902.8 9910.3 7436.3 7500.4 7511.0 7525.2 7671.8 7570.2 7646.3 7664.9 7703.2 7783.1 13:33:31 INFO - PID 16766 | 13:33:31 INFO - PID 16766 | [#4] prototype.html Cycles:180 Average:5455.50 Median:6118.39 stddev:1526.60 (25.0%) stddev-sans-first:1484.51 13:33:31 INFO - PID 16766 | Values: 480.2 502.7 504.5 510.0 531.3 6062.2 6149.9 6177.9 6191.6 6278.4 6107.6 6115.6 6116.2 6124.4 6125.9 5007.5 5049.4 5061.7 5117.7 5122.4 4821.1 5043.7 5045.4 5091.6 5107.7 4865.0 4898.9 4906.7 4924.6 4946.8 4915.3 4918.6 4925.5 4974.9 4978.1 4418.1 4420.5 4430.4 4446.4 4469.6 6082.2 6100.9 6143.5 6154.5 6161.5 5585.2 5634.2 5659.8 5664.7 5738.7 6386.0 6449.1 6474.4 6478.0 6485.7 4553.9 4782.8 4819.5 4821.3 4855.8 6629.3 6633.6 6749.1 6849.9 6853.8 4913.2 4913.3 4943.3 4950.2 4954.7 5037.0 5043.8 5044.0 5066.5 5083.7 5583.6 5604.4 5630.2 5638.5 5639.6 6506.4 6514.7 6556.3 6560.3 6564.3 1734.8 1736.9 1742.8 1746.3 1754.9 1154.4 1200.9 1206.3 1208.6 1222.0 5668.7 5693.9 5707.5 5728.8 5749.9 6535.3 6572.2 6574.0 6601.6 6633.7 5625.2 5627.2 5634.3 5693.7 5695.4 3504.6 3505.2 3505.5 3527.0 3531.3 6519.7 6522.1 6545.3 6591.8 6601.9 6779.5 6802.7 6809.5 6821.4 6849.7 6120.6 6428.2 6488.2 6505.9 6562.8 6541.3 6587.1 6650.3 6661.8 6690.0 6478.8 6597.5 6601.2 6611.6 6650.6 6387.9 6465.3 6494.2 6523.8 6540.1 4891.3 4901.6 4904.7 4921.2 4924.4 6258.3 6420.9 6481.5 6566.8 6692.2 6272.6 6367.8 6422.8 6459.5 6465.8 6348.4 6348.6 6368.0 6427.2 6461.4 6336.6 6353.0 6425.2 6434.8 6456.8 6518.6 6545.9 6551.5 6559.7 6591.9 6233.7 6234.2 6308.0 6310.3 6390.4 13:33:31 INFO - PID 16766 | 13:33:31 INFO - PID 16766 | [#5] yui.html Cycles:180 Average:24769.40 Median:18980.07 stddev:35708.49 (188.1%) stddev-sans-first:35763.72 13:33:31 INFO - PID 16766 | Values: 909.0 910.5 926.5 927.9 928.5 18297.6 19431.6 19431.9 19441.8 20569.6 22058.2 22150.1 22184.9 22250.3 22251.7 960.5 978.3 981.4 982.7 984.7 726.2 744.6 947.8 989.9 996.2 1034.5 1043.7 1044.0 1044.5 1044.9 1028.1 1035.5 1036.0 1036.6 1040.7 757.6 758.1 761.8 764.2 767.5 17656.3 18556.7 18837.2 18974.2 18985.9 21125.4 21319.7 21596.2 21632.5 21637.7 17413.8 17565.4 17588.9 17595.2 17624.1 3718.6 3726.9 3730.7 3733.7 3739.0 137034.0 137663.3 138548.6 139462.3 139519.0 16181.4 16662.9 16670.7 16706.3 16738.4 30831.4 31804.5 31935.2 31940.0 32043.0 8319.4 8573.7 8608.0 8692.9 8725.9 4139.7 4156.1 4162.2 4203.0 4216.3 4936.7 5151.8 5309.2 5952.6 6192.1 1121.3 1151.0 1158.1 1158.9 1164.9 671.2 674.4 684.2 693.4 700.1 11727.2 12502.7 12585.9 13148.5 13374.7 644.6 645.9 652.3 657.4 668.2 5802.3 5813.4 5816.9 5848.4 5916.6 123627.0 123837.0 124452.0 124893.0 128550.0 108317.0 108601.0 109120.5 110857.0 113505.4 97599.0 97673.0 99362.0 99738.0 100328.5 10969.1 11060.8 11062.8 11142.0 11199.7 45371.3 45998.5 46819.0 47313.0 47933.8 57786.4 57800.0 58645.7 58722.0 61091.0 15771.7 15795.3 15855.4 15861.8 15879.7 17551.1 17777.9 17930.6 17931.5 17984.6 14197.9 14285.9 14344.0 14366.3 14369.0 16511.7 16522.1 16614.1 16749.2 16795.7 16067.4 16162.0 16274.3 16360.2 16397.2 12153.1 12663.7 12676.2 12789.6 12811.0 12814.0 12849.9 12958.0 12991.1 13072.0 13:33:31 INFO - PID 16766 | -------- Summary: end -------- 13:33:31 INFO - PID 16766 | 13:33:32 INFO - TEST-INFO | 16766: exit 0 13:33:32 INFO - TEST-OK | dromaeo_css | took 1107359ms 13:33:32 INFO - TEST-START | kraken 13:33:32 INFO - Initialising browser for kraken test... 13:33:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43749/getInfo.html -profile /tmp/tmpjTSynE/profile 13:33:32 INFO - TEST-INFO | started process 17129 (/builds/slave/test/build/application/firefox/firefox http://localhost:43749/getInfo.html) 13:33:37 INFO - TEST-INFO | 17129: exit 0 13:33:37 INFO - Browser initialized. 13:33:37 INFO - Running cycle 1/1 for kraken test... 13:33:37 INFO - Using env: {'DISPLAY': ':0', 13:33:37 INFO - 'HOME': '/home/cltbld', 13:33:37 INFO - 'JSGC_DISABLE_POISONING': '1', 13:33:37 INFO - 'LANG': 'en_US.UTF-8', 13:33:37 INFO - 'LANGUAGE': 'en_US:en', 13:33:37 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 13:33:37 INFO - 'LOGNAME': 'cltbld', 13:33:37 INFO - 'MAIL': '/var/mail/cltbld', 13:33:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:33:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:33:37 INFO - 'MOZ_CRASHREPORTER': '1', 13:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:33:37 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 13:33:37 INFO - 'MOZ_NO_REMOTE': '1', 13:33:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:33:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:33:37 INFO - 'NO_EM_RESTART': '1', 13:33:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:33:37 INFO - 'PWD': '/builds/slave/test', 13:33:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:33:37 INFO - 'RUST_BACKTRACE': 'full', 13:33:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:33:37 INFO - 'SHELL': '/bin/bash', 13:33:37 INFO - 'SHLVL': '1', 13:33:37 INFO - 'STYLO_FORCE_DISABLED': '1', 13:33:37 INFO - 'TERM': 'linux', 13:33:37 INFO - 'TMOUT': '86400', 13:33:37 INFO - 'USER': 'cltbld', 13:33:37 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854', 13:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:33:37 INFO - '_': '/tools/buildbot/bin/python'} 13:33:37 INFO - TEST-INFO | started process 17361 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjTSynE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:33:38 INFO - PID 17361 | 13:33:38 INFO - PID 17361 | (/builds/slave/test/build/application/firefox/firefox:17411): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:33:38 INFO - PID 17361 | 13:33:49 INFO - PID 17361 | RSS: Main: 173719552 13:33:49 INFO - PID 17361 | 13:33:51 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43749/tests/kraken/driver.html?testName=audio-beat-detection) 13:33:52 INFO - PID 17361 | RSS: Main: 180011008 13:33:52 INFO - PID 17361 | 13:33:55 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43749/tests/kraken/driver.html?testName=audio-dft) 13:33:55 INFO - PID 17361 | RSS: Main: 180346880 13:33:55 INFO - PID 17361 | 13:33:58 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43749/tests/kraken/driver.html?testName=audio-fft) 13:33:59 INFO - PID 17361 | RSS: Main: 174964736 13:33:59 INFO - PID 17361 | 13:34:01 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43749/tests/kraken/driver.html?testName=audio-oscillator) 13:34:02 INFO - PID 17361 | RSS: Main: 175951872 13:34:02 INFO - PID 17361 | 13:34:04 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43749/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:34:04 INFO - PID 17361 | RSS: Main: 176283648 13:34:04 INFO - PID 17361 | 13:34:08 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43749/tests/kraken/driver.html?testName=imaging-darkroom) 13:34:08 INFO - PID 17361 | RSS: Main: 178593792 13:34:08 INFO - PID 17361 | 13:34:12 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43749/tests/kraken/driver.html?testName=imaging-desaturate) 13:34:13 INFO - PID 17361 | RSS: Main: 178626560 13:34:13 INFO - PID 17361 | 13:34:16 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43749/tests/kraken/driver.html?testName=json-parse-financial) 13:34:16 INFO - PID 17361 | RSS: Main: 178454528 13:34:16 INFO - PID 17361 | 13:34:18 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43749/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:34:18 INFO - PID 17361 | RSS: Main: 178667520 13:34:18 INFO - PID 17361 | 13:34:20 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43749/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:34:21 INFO - PID 17361 | RSS: Main: 178659328 13:34:21 INFO - PID 17361 | 13:34:23 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43749/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:34:24 INFO - PID 17361 | RSS: Main: 175763456 13:34:24 INFO - PID 17361 | 13:34:26 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43749/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:34:27 INFO - PID 17361 | RSS: Main: 176197632 13:34:27 INFO - PID 17361 | 13:34:30 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43749/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:34:30 INFO - PID 17361 | RSS: Main: 176029696 13:34:30 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | Cycle 1(1): loaded http://localhost:43749/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43749/tests/kraken/driver.html?testName=ai-astar) 13:34:32 INFO - PID 17361 | RSS: Main: 177393664 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | __start_tp_report 13:34:32 INFO - PID 17361 | _x_x_mozilla_page_load 13:34:32 INFO - PID 17361 | _x_x_mozilla_page_load_details 13:34:32 INFO - PID 17361 | |i|pagename|runs| 13:34:32 INFO - PID 17361 | |0;ai-astar;108;108;108;108;108;109;107;109;108;108 13:34:32 INFO - PID 17361 | |1;audio-beat-detection;122;117;115;117;127;117;116;117;119;146 13:34:32 INFO - PID 17361 | |2;audio-dft;160;162;162;162;161;163;167;165;167;164 13:34:32 INFO - PID 17361 | |3;audio-fft;74;75;74;73;74;73;74;74;72;74 13:34:32 INFO - PID 17361 | |4;audio-oscillator;78;80;79;80;79;81;79;79;79;82 13:34:32 INFO - PID 17361 | |5;imaging-gaussian-blur;110;114;113;113;115;114;114;114;114;110 13:34:32 INFO - PID 17361 | |6;imaging-darkroom;155;147;148;147;148;147;149;149;150;148 13:34:32 INFO - PID 17361 | |7;imaging-desaturate;94;95;95;94;94;94;94;95;94;100 13:34:32 INFO - PID 17361 | |8;json-parse-financial;58;62;57;58;57;54;55;55;55;54 13:34:32 INFO - PID 17361 | |9;json-stringify-tinderbox;37;39;39;38;38;38;47;39;39;38 13:34:32 INFO - PID 17361 | |10;stanford-crypto-aes;76;74;74;84;74;74;74;74;76;77 13:34:32 INFO - PID 17361 | |11;stanford-crypto-ccm;113;112;112;114;120;117;115;116;122;109 13:34:32 INFO - PID 17361 | |12;stanford-crypto-pbkdf2;182;168;169;166;168;182;175;172;167;170 13:34:32 INFO - PID 17361 | |13;stanford-crypto-sha256-iterative;67;65;66;66;68;67;65;71;66;64 13:34:32 INFO - PID 17361 | __end_tp_report 13:34:32 INFO - PID 17361 | __start_cc_report 13:34:32 INFO - PID 17361 | _x_x_mozilla_cycle_collect,973 13:34:32 INFO - PID 17361 | __end_cc_report 13:34:32 INFO - PID 17361 | __startTimestamp1505507672873__endTimestamp 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | ------- Summary: start ------- 13:34:32 INFO - PID 17361 | Number of tests: 14 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#0] ai-astar Cycles:10 Average:108.10 Median:108.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 13:34:32 INFO - PID 17361 | Values: 108.0 108.0 108.0 108.0 108.0 109.0 107.0 109.0 108.0 108.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:118.00 stddev:9.37 (7.9%) stddev-sans-first:9.93 13:34:32 INFO - PID 17361 | Values: 122.0 117.0 115.0 117.0 127.0 117.0 116.0 117.0 119.0 146.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.50 stddev:2.41 (1.5%) stddev-sans-first:2.24 13:34:32 INFO - PID 17361 | Values: 160.0 162.0 162.0 162.0 161.0 163.0 167.0 165.0 167.0 164.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#3] audio-fft Cycles:10 Average:73.70 Median:74.00 stddev:0.82 (1.1%) stddev-sans-first:0.87 13:34:32 INFO - PID 17361 | Values: 74.0 75.0 74.0 73.0 74.0 73.0 74.0 74.0 72.0 74.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:79.50 stddev:1.17 (1.5%) stddev-sans-first:1.09 13:34:32 INFO - PID 17361 | Values: 78.0 80.0 79.0 80.0 79.0 81.0 79.0 79.0 79.0 82.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:114.00 stddev:1.73 (1.5%) stddev-sans-first:1.42 13:34:32 INFO - PID 17361 | Values: 110.0 114.0 113.0 113.0 115.0 114.0 114.0 114.0 114.0 110.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:148.50 stddev:2.39 (1.6%) stddev-sans-first:1.05 13:34:32 INFO - PID 17361 | Values: 155.0 147.0 148.0 147.0 148.0 147.0 149.0 149.0 150.0 148.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.00 stddev:1.85 (2.0%) stddev-sans-first:1.94 13:34:32 INFO - PID 17361 | Values: 94.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 100.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:2.46 (4.3%) stddev-sans-first:2.55 13:34:32 INFO - PID 17361 | Values: 58.0 62.0 57.0 58.0 57.0 54.0 55.0 55.0 55.0 54.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#9] json-stringify-tinderbox Cycles:10 Average:39.20 Median:39.00 stddev:2.82 (7.2%) stddev-sans-first:2.88 13:34:32 INFO - PID 17361 | Values: 37.0 39.0 39.0 38.0 38.0 38.0 47.0 39.0 39.0 38.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:75.00 stddev:3.13 (4.2%) stddev-sans-first:3.32 13:34:32 INFO - PID 17361 | Values: 76.0 74.0 74.0 84.0 74.0 74.0 74.0 74.0 76.0 77.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#11] stanford-crypto-ccm Cycles:10 Average:115.00 Median:115.50 stddev:3.92 (3.4%) stddev-sans-first:4.09 13:34:32 INFO - PID 17361 | Values: 113.0 112.0 112.0 114.0 120.0 117.0 115.0 116.0 122.0 109.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:171.00 stddev:5.92 (3.5%) stddev-sans-first:5.02 13:34:32 INFO - PID 17361 | Values: 182.0 168.0 169.0 166.0 168.0 182.0 175.0 172.0 167.0 170.0 13:34:32 INFO - PID 17361 | 13:34:32 INFO - PID 17361 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.50 stddev:1.96 (2.9%) stddev-sans-first:2.07 13:34:32 INFO - PID 17361 | Values: 67.0 65.0 66.0 66.0 68.0 67.0 65.0 71.0 66.0 64.0 13:34:32 INFO - PID 17361 | -------- Summary: end -------- 13:34:32 INFO - PID 17361 | 13:34:33 INFO - PID 17361 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 13:34:33 INFO - TEST-INFO | 17361: exit 0 13:34:33 INFO - TEST-OK | kraken | took 60945ms 13:34:33 INFO - SUITE-END | took 1168s 13:34:34 INFO - Completed test suite (00:19:28) 13:34:34 INFO - javascript benchmark 13:34:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8493.264717275782, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3124.156477751007, 3127.2338277122108, 3128.436881361354, 3151.732102771265, 3177.5869137012187, 6110.663913484767, 6157.692115394229, 6200.0, 6254.874902501947, 6311.747530098791, 6094.390560943914, 6159.076138579216, 6207.844803879905, 6213.254409470858, 6221.191245138137, 5719.914201286973, 5777.277840269966, 5805.535557155438, 5805.680687562182, 5811.535077193836, 5782.9421705782825, 5849.61977471463, 5860.853478663046, 5876.853078673024, 5905.645661260338, 61.8649782915234, 5323.334583177122, 5610.579206559504, 5838.416158384149, 5841.795537156222, 5757.424257574251, 5758.452946970031, 5816.592838501307, 5831.79588714393, 5838.970805145947, 5698.373178950312, 5715.085586306171, 5812.622179558315, 5813.593048486608, 5823.301203855522, 6229.813105606839, 6234.0960560718895, 6258.24901011877, 6266.843328916813, 6330.113784070234, 6670.299618540081, 6691.065833056084, 6803.217629972518, 6806.897896531556, 6810.046593476917, 6639.468842492589, 6740.056392105109, 6743.5953842769595, 6748.392644661955, 6863.450923926124, 6142.84642883927, 6209.96895015522, 6217.347178546278, 6218.347073557302, 6221.9422698141425, 6753.223379311393, 6873.381395674313, 6874.484413668994, 6878.449724022066, 6884.277150899184, 6611.438027767597, 6612.669366531654, 6695.096162018162, 6704.899426508607, 6706.865862682719, 6590.9670451647435, 6728.562643428161, 6731.394174524316, 6801.2178599461695, 6804.11546498952, 6612.801615951529, 6620.668966551653, 6752.729890804313, 6756.2568117507035, 6756.83107922296, 6699.39705426514, 6760.797176084725, 6816.147981502312, 6823.590584564942, 6823.69293381799, 5078.898422031612, 5105.9489405106215, 5173.741312934339, 5174.275601415805, 5182.067227898939, 4465.330200469955, 4483.484399294058, 4525.321201819754, 4545.409096817392, 4580.335851301609, 5829.708514574254, 5841.561882858802, 5903.291605007426, 5904.645721256738, 5906.143609176731, 6748.426383757435, 6751.797446076625, 6828.180618325733, 6840.486963477759, 6843.213030501456, 5573.052581061148, 5643.971780141073, 5669.603127781098, 5780.046292361797, 5857.062869940874, 5695.288088988877, 5721.885562288731, 5742.942570574324, 5790.42095790426, 5793.8551536211935, 6573.94816829305, 6608.008798680236, 6612.173478315211, 6635.0, 6643.40209381158, 6487.610743355413, 6570.408663220333, 6615.106960560262, 6645.734170633217, 6749.122614060141, 6537.542372033912, 6654.13496245485, 6655.334466553306, 6656.800295991128, 6657.7669781557415, 6510.153680021567, 6576.079348891255, 6625.801225963229, 6714.194296684233, 6721.294264102395, 6547.901781473377, 6710.362515561014, 6723.125993620798, 6727.260001399744, 6736.865262694817, 6590.868182636224, 6608.70260838273, 6613.206415230203, 6634.0, 6647.767328143492, 6519.152510173647, 6523.217213934168, 6598.802035938929, 6600.60396376219, 6607.0, 6246.968765156145, 6462.483001359974, 6470.48236141117, 6556.967215163893, 6566.80299591013, 6485.675716214076, 6498.610083395011, 6509.576877502947, 6605.207375115017, 6619.834504137435, 6335.208098987627, 6335.3981371769605, 6431.48548116159, 6456.160699109086, 6466.676666166767], "value": 6072.141472729982, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.2748040503749, 827.1397746343803, 828.3042244514606, 830.410408609887, 838.1785849867128, 39554.208915821706, 43050.0, 43168.56831431685, 43214.78392608037, 43368.99999999996, 38778.80610596943, 39052.0, 39142.21715565687, 39211.60788392122, 39226.21547569047, 7005.124359455068, 7097.290270972887, 7139.143302803671, 7805.180456052118, 7973.202679732039, 7928.167542408022, 7987.600619969007, 8021.478603890758, 8032.236937490929, 8033.4778239414545, 7934.087579928296, 7936.722214722486, 7973.40199485038, 7997.040355157389, 8021.0, 7859.135495095535, 7902.565358905258, 7957.403194760387, 7985.001874765654, 8003.1996800320085, 7887.526748395057, 7896.881546776803, 7907.96046019772, 7948.165442628499, 7959.363250939911, 37488.25023499515, 37665.0, 37777.62222377768, 37847.0, 38041.0, 3607.4408466687705, 3613.4579813027995, 3632.037510059836, 3638.9818050909576, 3659.597444281127, 31810.840945795124, 31873.203169920707, 32048.839755801306, 32055.83972080148, 32084.518732219036, 5271.789128434858, 5276.366835979707, 5313.734313284359, 5338.893222135574, 5355.3305836770405, 187721.0, 188208.0, 188865.05567472213, 188943.0, 189031.0, 42259.15481690349, 48237.31169409124, 48552.5144748555, 48984.265236021485, 49185.26222106671, 44812.55187448149, 49447.50552494429, 50343.0, 50512.494875050776, 50604.493955060716, 16259.756103658454, 16351.0, 16451.753223701653, 16465.91767041157, 16515.0, 13446.596602101952, 13454.730905381837, 13483.865161348458, 13491.662708432368, 13552.254625995665, 13556.45774169042, 13561.86438135606, 13652.0, 13746.0, 13839.30803459843, 5498.202760599691, 5543.667379957215, 5565.916511252334, 5653.434656534396, 5689.743961521742, 1006.6376104602338, 1011.3931641015479, 1011.4184344002228, 1025.8102251083574, 1025.9692209233735, 22546.21088261903, 22643.43391415228, 22690.319290421314, 22726.09095636156, 22935.54128917412, 1086.2613422872364, 1110.7889500994947, 1113.6492005018392, 1118.3960661242838, 1119.9440027998567, 12075.09436792244, 12139.089568282237, 12156.149069565046, 12167.75664486723, 12264.81602775959, 223491.0, 226942.0, 227301.0, 227596.0, 228157.0, 52144.0, 52589.7370513145, 52884.73557632187, 52894.735526322125, 53001.2049819253, 1419.332913530639, 1423.1532238318184, 1428.0931608428514, 1441.7188648213498, 1444.978325325121, 21896.562068758853, 22106.668399974336, 22308.665370019786, 22540.0, 22606.5478690422, 33410.832945835115, 36858.8157059213, 36952.26095478021, 36985.44521832228, 37054.44418333781, 194378.0, 194896.0, 195483.0, 195619.0, 196151.0, 30225.0, 30732.84633576818, 32726.0, 32836.34327313393, 32849.0, 42696.35955460608, 42889.35665964951, 42973.7851310754, 43021.35467968046, 43268.0, 31833.522497163023, 31963.0, 32119.678803211667, 32480.512792308607, 33024.50463243005, 31008.772586042876, 31232.843835780677, 31239.219019524695, 31489.8425507871, 31762.935409199254, 35140.0, 35453.290934181685, 35462.645373545936, 35502.04738739394, 35625.46561801627, 30829.22926926755, 31072.53391199179, 31213.687863121988, 31603.525947111273, 31771.0, 22752.886235568716, 23091.0, 23350.59896406221, 23660.408489787893, 23669.053237870292], "value": 17035.815776526073, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.2310572962177, 574.6552068758742, 574.7126436781612, 578.8379253808934, 590.681032242589, 21475.570488590238, 22337.888310558465, 22389.664155037688, 22439.77560224397, 22471.775282247174, 23340.883295583542, 24285.15001974931, 24607.876960615216, 24695.382615434624, 24903.75096249037, 6361.332060133689, 6560.245571759261, 6573.704183311763, 6575.375339342755, 6583.177102862142, 6344.2386913570435, 6441.8711625767455, 6511.869762604745, 6536.182977127859, 6591.835204119888, 6569.540132190748, 6588.143541339619, 6588.407043366096, 6590.50571207159, 6594.6043237405665, 6341.8414539636415, 6567.277599464055, 6567.934320656804, 6568.343165683417, 6568.474522038226, 6098.298695650013, 6108.053251745987, 6110.541709371771, 6132.110843927606, 6137.41694539018, 29048.70951290481, 29586.556201656993, 29707.0, 29779.95770148056, 29830.40339193204, 1994.1225860621425, 2042.0606521000357, 2043.7751847296786, 2046.1099421751585, 2049.661805802041, 19103.140358683893, 19453.513662158417, 20075.09662065196, 20133.39599812008, 20206.696899546518, 2451.2891261534132, 2462.3597864555168, 2503.2740505253455, 2508.3478295643263, 2526.7094284157374, 114916.0, 122645.0, 126552.0, 133356.0, 142796.28601856926, 14569.708605828037, 14574.03550530592, 14680.779788303184, 14776.48282310114, 14836.554903352917, 23209.535809284058, 23235.419114522272, 23282.65076023861, 23292.41768955756, 23305.300840974458, 10203.7449063774, 10220.284580079471, 10352.5341359639, 10379.325343852626, 10441.373517588969, 9645.517724113766, 9699.612015519442, 9766.827980642369, 9820.0, 9896.356736812228, 8877.378583499092, 8989.640414383352, 9246.630134794534, 9268.397554158959, 9332.72001839946, 2555.5527782637964, 2573.318070711263, 2663.746944040313, 2665.120510231619, 2712.145674112648, 925.546482223719, 939.7133874168444, 943.9952800236092, 954.6038394066524, 954.7756277274835, 19044.71432928507, 19116.90441547811, 19308.613827723366, 19459.708104378446, 19650.90174549147, 1014.4826138669332, 1015.6292953072076, 1020.7601213714771, 1035.699647102347, 1045.4041196518065, 10667.359958402521, 10739.516721747541, 10860.67417977462, 10944.56221751137, 11037.227394082338, 472072.0, 474266.0, 477672.0, 484596.0, 491749.0, 115245.0, 119947.40026299987, 119957.40021299837, 120706.3964680171, 121598.39200803939, 1106.5850306135198, 1117.2961034548346, 1149.2931846914087, 1154.1343992005995, 1155.3645494977898, 17362.305507779547, 17403.390881319094, 17990.910045449687, 18050.90974545119, 18159.182836772114, 53872.0, 54753.0, 55027.0, 56060.0, 57913.0, 68194.0, 68708.0, 68732.31267687259, 69263.0, 69620.65189674222, 14162.645933851736, 14304.0, 14517.782233266298, 14521.128732276096, 14643.56069317922, 22625.09499619997, 22701.772982269966, 22955.311340659806, 23098.653520196873, 23277.185298514472, 18008.549786254924, 18106.818931810514, 18240.635187295913, 18261.81738182601, 18550.44348669542, 15956.202189890228, 16663.50009499717, 16788.160591970598, 16935.491935241964, 16964.0, 17862.5534361642, 17918.372856949944, 17971.10144492744, 18012.819871801115, 18153.727694084864, 14211.360488778237, 14261.786073209118, 14264.286785660883, 14320.641983950069, 14393.92803035978, 17744.3789467368, 17881.73177402314, 17981.100944952963, 18202.180901859185, 18378.448646540626], "value": 11857.795841653968, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.340783016322, 1365.194535224218, 1401.5164768154982, 1407.8873690104797, 1413.4840783114166, 15130.319135638894, 15799.210039498008, 15807.999999999985, 15810.920945395257, 15811.972221805574, 15405.998610090337, 15437.073775573474, 15503.612409689764, 15517.456889008887, 15587.532374028797, 2766.1563223216913, 2831.0940499040366, 2857.4856525825344, 2862.069827306134, 2863.455943370763, 2786.6656001279775, 2807.1578526442086, 2824.124521398373, 2828.462592107493, 2835.7022512636186, 2681.9731802681918, 2786.749192572668, 2864.9713502865015, 2865.699101594334, 2866.2117917572627, 2809.6909339972585, 2858.628378310827, 2880.323124065843, 2883.207118042534, 2884.9134525964255, 2247.8538894971775, 2351.000824649534, 2728.399752054545, 2755.3800394911186, 2791.9302017449522, 15199.924000380039, 15246.0, 15278.923605382013, 15321.770173447296, 15323.923380383138, 8061.63722632477, 8365.414420990533, 8367.414281000332, 8368.456050356708, 8370.790730231733, 5460.76108795392, 5613.2422123013275, 5699.202111704365, 5795.275590551181, 5807.332156801979, 1940.4760714607119, 1960.9313674021485, 1970.4975231315923, 2014.4057503036493, 2014.5567975045467, 26733.331666708295, 27059.999999999804, 27152.45695086107, 27187.0, 27252.0, 7439.628018599048, 7472.40220782347, 7679.616019199129, 7760.495567788189, 7774.222577742181, 17415.651686966445, 17480.650386992187, 17486.213120409357, 17486.91256543709, 17636.20637071309, 8052.235037671412, 8226.05400378952, 8228.42401031934, 8229.25936665703, 8230.0, 7451.701931922662, 7521.194913220897, 7546.547207167588, 7553.622318884034, 7570.432217583703, 5001.349554646958, 5177.456367081478, 5266.868328291824, 5281.815136470282, 5350.27771250884, 1546.883983701227, 1557.7896983907253, 1557.797486326793, 1561.2662048837028, 1562.1174036669245, 835.2065537739172, 874.2656168818223, 879.4679219072369, 881.7442941547008, 912.8813254277035, 7198.244184360567, 7263.92736072643, 7290.161631412456, 7290.198078211392, 7304.561726296439, 697.7906628011576, 706.8974998625171, 711.0933559711306, 712.6116266634697, 713.1584730018628, 4294.935575966363, 4946.802127914915, 5296.576273898079, 5439.755211015514, 5643.153526970905, 21035.368938931857, 22140.2250921217, 22237.777622223894, 22260.88869555642, 22421.775782242294, 21249.468763280733, 21921.232756853435, 22059.558808824055, 22119.889400552893, 22329.776702233095, 10934.781304373868, 10969.067629251444, 10984.06635435981, 11068.169887258488, 11086.279391839505, 8305.584720764058, 8537.274331681876, 8550.615222314886, 8703.564821759013, 8754.124587541195, 18396.356127535473, 18657.0, 18868.05659717036, 18920.716189257448, 19236.519087022938, 19298.13158407847, 19661.0, 19770.60458790845, 19993.700094498887, 20050.699239511127, 10931.344119352865, 10979.28634638746, 11129.94265544772, 11134.944325278646, 11203.551857925917, 13883.65290867696, 14135.43458261658, 14151.221682807545, 14157.36291866848, 14161.787573186617, 11516.366599837089, 11584.652460426201, 11779.234349767237, 11840.407979600814, 11943.402829858298, 11251.268667536584, 11304.321740695585, 11501.367424791717, 11519.366434846164, 11582.0, 9815.460149691835, 9885.15976142036, 9900.653477128266, 9902.752431189278, 9910.256730745079, 7436.330730234088, 7500.362469189961, 7511.0, 7525.172231054689, 7671.810869315265, 7570.16728159913, 7646.311831935152, 7664.923350766644, 7703.191164927826, 7783.143854176149], "value": 6739.435996884955, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.1812908990172, 502.7406347100514, 504.4526688542928, 509.9974500127499, 531.285421108609, 6062.181605483259, 6149.907751383733, 6177.876442471148, 6191.566590338678, 6278.37216278373, 6107.633541987473, 6115.602485838417, 6116.204893363856, 6124.356942521038, 6125.9387406125925, 5007.4992500749995, 5049.4193167785625, 5061.670991385567, 5117.7185254810975, 5122.41205226381, 4821.132196204681, 5043.722595257259, 5045.369328833895, 5091.592672586203, 5107.69353838769, 4864.975675121619, 4898.926516102261, 4906.6565340426005, 4924.581410580105, 4946.752662366867, 4915.3364295819965, 4918.5573298403315, 4925.458199598059, 4974.850754477371, 4978.128827455217, 4418.1384629997165, 4420.535843736426, 4430.357598148283, 4446.444194475691, 4469.642428605704, 6082.178905847698, 6100.908486372708, 6143.508519318444, 6154.5384096192965, 6161.476274516672, 5585.162225666142, 5634.211210430543, 5659.830205093854, 5664.660120392789, 5738.684372359518, 6386.0, 6449.12936753537, 6474.446132928063, 6477.967610161966, 6485.7405703772265, 4553.886152846172, 4782.760861956888, 4819.5180481951875, 4821.325014497973, 4855.757212139414, 6629.303923088055, 6633.6019838809825, 6749.12261406019, 6849.863002739918, 6853.828654283633, 4913.1893237616105, 4913.263010548446, 4943.2585112233455, 4950.2079667253065, 4954.727489988013, 5036.974815125901, 5043.773030213649, 5043.974780126149, 5066.544011039025, 5083.669561478492, 5583.553315734732, 5604.411536788579, 5630.18362337467, 5638.464345887134, 5639.6334238274385, 6506.41442270198, 6514.706838192198, 6556.27880933097, 6560.343965603402, 6564.277929427759, 1734.7918249810104, 1736.905749377884, 1742.817004214539, 1746.3363921709922, 1754.9385771498191, 1154.365099195456, 1200.9459574319176, 1206.288289908958, 1208.64949164743, 1221.9816702749465, 5668.716564171775, 5693.857653558694, 5707.543396528268, 5728.770849165987, 5749.91375129365, 6535.346465353522, 6572.178477690289, 6574.0, 6601.570897891622, 6633.668316584151, 5625.184348269478, 5627.240322556484, 5634.267545219177, 5693.66198943248, 5695.433755717128, 3504.56312911705, 3505.211327451303, 3505.5267538882435, 3526.982365088158, 3531.29374125176, 6519.706613202417, 6522.054302126166, 6545.345465453512, 6591.835204119935, 6601.933980660228, 6779.457643388616, 6802.65986700663, 6809.489288303298, 6821.372367796587, 6849.726010959505, 6120.571559990847, 6428.200103970896, 6488.188976377953, 6505.934940650533, 6562.8359291018105, 6541.34586541342, 6587.122670039016, 6650.301718319507, 6661.800145995628, 6689.966550167218, 6478.805635830932, 6597.505187111082, 6601.174853143357, 6611.636360000246, 6650.600963941985, 6387.891995641335, 6465.288818230085, 6494.188226471691, 6523.836904077246, 6540.084388185753, 4891.315215869852, 4901.607871370211, 4904.705717656952, 4921.212605983025, 4924.359833221658, 6258.280297765815, 6420.935790642221, 6481.5138864584405, 6566.770163044271, 6692.230393504809, 6272.623642581277, 6367.777127800505, 6422.807315780534, 6459.547831651739, 6465.838354040999, 6348.428641422294, 6348.619082855043, 6368.0, 6427.228732552124, 6461.418472337325, 6336.619802811846, 6353.0, 6425.1968503937005, 6434.806955791334, 6456.806295811133, 6518.57629254097, 6545.934540654533, 6551.50863685216, 6559.7376104955265, 6591.868162636624, 6233.719482623203, 6234.220722409699, 6307.96846015767, 6310.2743184534365, 6390.4248617624635], "value": 5004.741483131097, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.0409617853165, 910.544727636182, 926.5367316341827, 927.8979312275649, 928.5125309212667, 18297.63404731906, 19431.611367772635, 19431.902840485796, 19441.805581944176, 20569.588608227827, 22058.227962021334, 22150.113995440202, 22184.88907555464, 22250.332490025285, 22251.666225006637, 960.5149399553216, 978.3200675530509, 981.4160574458186, 982.670805280232, 984.6996666016862, 726.2156870579788, 744.6462930108182, 947.8199142163002, 989.9257555683316, 996.2229461020415, 1034.4982683398546, 1043.6973277749444, 1044.0290529807276, 1044.5038606661851, 1044.8798388185342, 1028.059325716968, 1035.5495359518595, 1036.0468369100408, 1036.6319956415514, 1040.6877936618985, 757.5833291689604, 758.0524344569288, 761.8476304739074, 764.170874601053, 767.4781148818856, 17656.293748250053, 18556.721649175273, 18837.24651013958, 18974.241030358768, 18985.90507047456, 21125.366239012856, 21319.68020479694, 21596.24413145548, 21632.459188520257, 21637.675434868488, 17413.82586174135, 17565.385211517536, 17588.91205543977, 17595.208215630326, 17624.118794060247, 3718.5537735471644, 3726.9440958385644, 3730.6642402183934, 3733.6826369758605, 3739.0, 137034.0, 137663.31168344093, 138548.61451385458, 139462.30268848693, 139519.0, 16181.352745890268, 16662.916910400785, 16670.66658666844, 16706.331746730237, 16738.414155504743, 30831.383372332428, 31804.522932156036, 31935.201619959687, 31940.0, 32043.0, 8319.376046796515, 8573.74278771638, 8608.0, 8692.869606955901, 8725.91274087251, 4139.689523285705, 4156.085661154541, 4162.209180255742, 4202.978985105055, 4216.3464662977285, 4936.703797772145, 5151.8196863109615, 5309.203619457112, 5952.642841429441, 6192.102145188923, 1121.3440137519478, 1150.9942450287695, 1158.1082566423806, 1158.9420528973685, 1164.9068074554016, 671.214678825775, 674.3896773420063, 684.1687349869932, 693.389816961071, 700.0514303119302, 11727.179097463095, 12502.74994500105, 12585.874141258653, 13148.474061037643, 13374.732505349839, 644.6100109433819, 645.9354064593597, 652.3248437866785, 657.3853447027045, 668.2115104177118, 5802.274715660543, 5813.389594092644, 5816.883662326814, 5848.355648639957, 5916.585838991229, 123627.0, 123837.0, 124452.0, 124893.0, 128550.0, 108317.0, 108601.0, 109120.45439772909, 110857.0, 113505.43247283711, 97599.0, 97673.0, 99362.0, 99738.0, 100328.49835750775, 10969.122470202205, 11060.778784424105, 11062.834057489305, 11142.0, 11199.66401007971, 45371.319430207914, 45998.54001460077, 46819.0, 47313.0, 47933.76033119812, 57786.422135778106, 57800.0, 58645.70677146587, 58722.0, 61091.0, 15771.68456630838, 15795.289211985259, 15855.365785368918, 15861.841381586037, 15879.68240635204, 17551.1224438775, 17777.911110444365, 17930.641387171923, 17931.462056138338, 17984.550386239924, 14197.929010354883, 14285.857131429253, 14344.0, 14366.281685915872, 14369.0, 16511.669766604842, 16522.09128497944, 16614.086225257728, 16749.162541872614, 16795.664086718443, 16067.437639682556, 16162.0, 16274.349026038826, 16360.181990900646, 16397.180140992663, 12153.088518361335, 12663.65103720379, 12676.23942563449, 12789.552365667529, 12811.0, 12814.0, 12849.87150128524, 12958.0, 12991.090623656253, 13072.0], "value": 9072.104727487187, "unit": "score"}]}, {"extraOptions": ["e10s", "stylo_disabled"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1427.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 108.0, 108.0, 108.0, 109.0, 107.0, 109.0, 108.0, 108.0], "value": 108.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 117.0, 115.0, 117.0, 127.0, 117.0, 116.0, 117.0, 119.0, 146.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 162.0, 161.0, 163.0, 167.0, 165.0, 167.0, 164.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 73.0, 74.0, 73.0, 74.0, 74.0, 72.0, 74.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 79.0, 80.0, 79.0, 81.0, 79.0, 79.0, 79.0, 82.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 113.0, 113.0, 115.0, 114.0, 114.0, 114.0, 114.0, 110.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 147.0, 148.0, 147.0, 148.0, 147.0, 149.0, 149.0, 150.0, 148.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 100.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 57.0, 58.0, 57.0, 54.0, 55.0, 55.0, 55.0, 54.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 39.0, 39.0, 38.0, 38.0, 38.0, 47.0, 39.0, 39.0, 38.0], "value": 39.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 74.0, 84.0, 74.0, 74.0, 74.0, 74.0, 76.0, 77.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 112.0, 114.0, 120.0, 117.0, 115.0, 116.0, 122.0, 109.0], "value": 115.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 168.0, 169.0, 166.0, 168.0, 182.0, 175.0, 172.0, 167.0, 170.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 66.0, 68.0, 67.0, 65.0, 71.0, 66.0, 64.0], "value": 66.5, "unit": "score"}]}]} 13:34:34 INFO - Return code: 0 13:34:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:34:34 INFO - # TBPL SUCCESS # 13:34:34 INFO - Running post-action listener: _package_coverage_data 13:34:34 INFO - Running post-action listener: _resource_record_post_action 13:34:34 INFO - [mozharness: 2017-09-15 20:34:34.136555Z] Finished run-tests step (success) 13:34:34 INFO - Running post-run listener: _resource_record_post_run 13:34:34 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 16384; Write bytes: 335319040; Read time: 252; Write time: 156728 13:34:34 INFO - TinderboxPrint: CPU usage
13.1% 13:34:34 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 252 13:34:34 INFO - TinderboxPrint: I/O write bytes / time
335,319,040 / 156,728 13:34:34 INFO - TinderboxPrint: CPU idle
8,222.3 (86.9%) 13:34:34 INFO - TinderboxPrint: CPU user
1,192.0 (12.6%) 13:34:34 INFO - TinderboxPrint: Swap in / out
0 / 0 13:34:34 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13983744; Read time: 0; Write time: 3620 13:34:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:34:34 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 12288; Write bytes: 321314816; Read time: 244; Write time: 153104 13:34:34 INFO - Running post-run listener: _upload_blobber_files 13:34:34 INFO - Blob upload gear active. 13:34:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:34:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:34:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:34:35 INFO - (blobuploader) - INFO - Open directory for files ... 13:34:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:34:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:34:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:34:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:34:36 INFO - (blobuploader) - INFO - Done attempting. 13:34:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log ... 13:34:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:34:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_raw.log: uploaded 13:34:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:34:37 INFO - (blobuploader) - INFO - Done attempting. 13:34:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:34:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:34:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:34:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:34:44 INFO - (blobuploader) - INFO - Done attempting. 13:34:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log ... 13:34:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:34:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_errorsummary.log: uploaded 13:34:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:34:45 INFO - (blobuploader) - INFO - Done attempting. 13:34:45 INFO - (blobuploader) - INFO - Iteration through files over. 13:34:45 INFO - Return code: 0 13:34:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:34:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:34:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48681864c8dd0edfb0f7e3dbe7be7792515cd0f89741e4caa17b58d2820c608b83d05009cc900d9e6e6a09e70a8da5b182b683ff53ecd666d164beec941ac1a8", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e3de041dc91e9bd6bcd234e7ed4a38820fdab65b4f7b3fb810cc38019b07ce8bba51b468318bda584020e70feb661ad160bd7901c1c516d4858dc9237c9bfe2c", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5d6a528ac5f34d33b139a1cf139c3675f390b6f6236c1103b68f9c809df7dadb978cf2d6c4e8e99152acd2302ead8648e1d498643928ca131f682b7d143f446c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b7edb72b2da75ade312a46065b9b3bc518697779a5627c06c4a103e5d9047289d08c56dcefbb4bbcf4018af2cc847068e5318160034ad2c3b8ac7a229599425"} 13:34:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:34:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:34:45 INFO - Contents: 13:34:45 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48681864c8dd0edfb0f7e3dbe7be7792515cd0f89741e4caa17b58d2820c608b83d05009cc900d9e6e6a09e70a8da5b182b683ff53ecd666d164beec941ac1a8", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e3de041dc91e9bd6bcd234e7ed4a38820fdab65b4f7b3fb810cc38019b07ce8bba51b468318bda584020e70feb661ad160bd7901c1c516d4858dc9237c9bfe2c", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5d6a528ac5f34d33b139a1cf139c3675f390b6f6236c1103b68f9c809df7dadb978cf2d6c4e8e99152acd2302ead8648e1d498643928ca131f682b7d143f446c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b7edb72b2da75ade312a46065b9b3bc518697779a5627c06c4a103e5d9047289d08c56dcefbb4bbcf4018af2cc847068e5318160034ad2c3b8ac7a229599425"} 13:34:45 INFO - Running post-run listener: copy_logs_to_upload_dir 13:34:45 INFO - Copying logs to upload dir... 13:34:45 INFO - mkdir: /builds/slave/test/build/upload/logs 13:34:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1331.233237 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 11 secs) (at 2017-09-15 13:34:45.126256) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 13:34:45.134223) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854 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/48681864c8dd0edfb0f7e3dbe7be7792515cd0f89741e4caa17b58d2820c608b83d05009cc900d9e6e6a09e70a8da5b182b683ff53ecd666d164beec941ac1a8", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e3de041dc91e9bd6bcd234e7ed4a38820fdab65b4f7b3fb810cc38019b07ce8bba51b468318bda584020e70feb661ad160bd7901c1c516d4858dc9237c9bfe2c", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5d6a528ac5f34d33b139a1cf139c3675f390b6f6236c1103b68f9c809df7dadb978cf2d6c4e8e99152acd2302ead8648e1d498643928ca131f682b7d143f446c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b7edb72b2da75ade312a46065b9b3bc518697779a5627c06c4a103e5d9047289d08c56dcefbb4bbcf4018af2cc847068e5318160034ad2c3b8ac7a229599425"} build_url:https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011292 build_url: 'https://queue.taskcluster.net/v1/task/Y6QodXyFSlC9NvyQ7Y-KNQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48681864c8dd0edfb0f7e3dbe7be7792515cd0f89741e4caa17b58d2820c608b83d05009cc900d9e6e6a09e70a8da5b182b683ff53ecd666d164beec941ac1a8", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e3de041dc91e9bd6bcd234e7ed4a38820fdab65b4f7b3fb810cc38019b07ce8bba51b468318bda584020e70feb661ad160bd7901c1c516d4858dc9237c9bfe2c", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5d6a528ac5f34d33b139a1cf139c3675f390b6f6236c1103b68f9c809df7dadb978cf2d6c4e8e99152acd2302ead8648e1d498643928ca131f682b7d143f446c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b7edb72b2da75ade312a46065b9b3bc518697779a5627c06c4a103e5d9047289d08c56dcefbb4bbcf4018af2cc847068e5318160034ad2c3b8ac7a229599425"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 13:34:45.165502) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:34:45.165846) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1505506226.116395-2094887854 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004611 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:34:45.248685) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 13:34:45.252012) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 13:34:45.252306) ========= ========= Total master_lag: 0.19 =========