builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-087 starttime: 1503944097.02 results: success (0) revision: 3529b653ede26f990eb7320649015294ad0f8e76 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.017744) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.018272) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.018559) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.035621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.036020) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-28 11:14:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2017-08-28 11:14:57 (46.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137443 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.189305) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.189743) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017275 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.240652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.241148) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3529b653ede26f990eb7320649015294ad0f8e76 --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 3529b653ede26f990eb7320649015294ad0f8e76 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-28 11:14:57,338 truncating revision to first 12 chars 2017-08-28 11:14:57,338 Setting DEBUG logging. 2017-08-28 11:14:57,338 attempt 1/10 2017-08-28 11:14:57,338 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3529b653ede2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-28 11:14:57,756 unpacking tar archive at: mozilla-central-3529b653ede2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.735983 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.992366) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.992836) ========= script_repo_revision: 3529b653ede26f990eb7320649015294ad0f8e76 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.993250) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:57.993515) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 11:14:58.006399) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-28 11:14:58.006839) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:14:58 INFO - MultiFileLogger online at 20170828 11:14:58 in /builds/slave/test 11:14:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:14:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:14:58 INFO - {'append_to_log': False, 11:14:58 INFO - 'base_work_dir': '/builds/slave/test', 11:14:58 INFO - 'blob_upload_branch': 'Firefox', 11:14:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:14:58 INFO - 'branch': 'Firefox', 11:14:58 INFO - 'buildbot_json_path': 'buildprops.json', 11:14:58 INFO - 'code_coverage': False, 11:14:58 INFO - 'config_files': ('talos/linux_config.py',), 11:14:58 INFO - 'default_actions': ('clobber', 11:14:58 INFO - 'read-buildbot-config', 11:14:58 INFO - 'download-and-extract', 11:14:58 INFO - 'populate-webroot', 11:14:58 INFO - 'create-virtualenv', 11:14:58 INFO - 'install', 11:14:58 INFO - 'setup-mitmproxy', 11:14:58 INFO - 'run-tests'), 11:14:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:14:58 INFO - 'disable_ccov_upload': False, 11:14:58 INFO - 'download_minidump_stackwalk': True, 11:14:58 INFO - 'download_symbols': 'ondemand', 11:14:58 INFO - 'enable_stylo': False, 11:14:58 INFO - 'enable_webrender': False, 11:14:58 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:14:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:14:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:14:58 INFO - 'gecko_profile': False, 11:14:58 INFO - 'gecko_profile_interval': 0, 11:14:58 INFO - 'installer_path': 'installer.exe', 11:14:58 INFO - 'log_level': 'info', 11:14:58 INFO - 'log_name': 'talos', 11:14:58 INFO - 'log_to_console': True, 11:14:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:14:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:14:58 INFO - 'opt_config_files': (), 11:14:58 INFO - 'pip_index': False, 11:14:58 INFO - 'suite': 'dromaeojs-e10s', 11:14:58 INFO - 'system_bits': '32', 11:14:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:14:58 INFO - 'title': 'talos-linux64-ix-087', 11:14:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:14:58 INFO - 'use_talos_json': True, 11:14:58 INFO - 'verify': 'False', 11:14:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:14:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:14:58 INFO - 'work_dir': 'build'} 11:14:58 INFO - [mozharness: 2017-08-28 18:14:58.234150Z] Running clobber step. 11:14:58 INFO - Running pre-action listener: _resource_record_pre_action 11:14:58 INFO - Running main action method: clobber 11:14:58 INFO - rmtree: /builds/slave/test/build 11:14:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:14:59 INFO - Running post-action listener: _resource_record_post_action 11:14:59 INFO - [mozharness: 2017-08-28 18:14:59.765966Z] Finished clobber step (success) 11:14:59 INFO - [mozharness: 2017-08-28 18:14:59.766064Z] Running read-buildbot-config step. 11:14:59 INFO - Running pre-action listener: _resource_record_pre_action 11:14:59 INFO - Running main action method: read_buildbot_config 11:14:59 INFO - Using buildbot properties: 11:14:59 INFO - { 11:14:59 INFO - "project": "", 11:14:59 INFO - "product": "firefox", 11:14:59 INFO - "who": "ffxbld@noreply.mozilla.org", 11:14:59 INFO - "installer_path": "public/build/target.tar.bz2", 11:14:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 11:14:59 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:14:59 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 11:14:59 INFO - "stage_platform": "linux64-stylo", 11:14:59 INFO - "basedir": "/builds/slave/test", 11:14:59 INFO - "buildnumber": 284, 11:14:59 INFO - "platform": "ubuntu64_hw_stylo", 11:14:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:14:59 INFO - "slavebuilddir": "test", 11:14:59 INFO - "taskId": "EOQYmiwfQpWGPy06CdaKzA", 11:14:59 INFO - "branch": "mozilla-central", 11:14:59 INFO - "script_repo_revision": "production", 11:14:59 INFO - "revision": "3529b653ede26f990eb7320649015294ad0f8e76", 11:14:59 INFO - "slavename": "talos-linux64-ix-087", 11:14:59 INFO - "repo_path": "mozilla-central" 11:14:59 INFO - } 11:14:59 INFO - Finding installer, test and symbols from parent task. 11:14:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EOQYmiwfQpWGPy06CdaKzA'}, attempt #1 11:15:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EOQYmiwfQpWGPy06CdaKzA'}, attempt #1 11:15:00 INFO - Task dependencies: RmbeUV6sREG6fr33Z_wCNQ 11:15:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ'}, attempt #1 11:15:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.tar.bz2 11:15:01 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.test_packages.json 11:15:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.crashreporter-symbols.zip 11:15:01 INFO - Running post-action listener: _resource_record_post_action 11:15:01 INFO - [mozharness: 2017-08-28 18:15:01.166270Z] Finished read-buildbot-config step (success) 11:15:01 INFO - [mozharness: 2017-08-28 18:15:01.166485Z] Running download-and-extract step. 11:15:01 INFO - Running pre-action listener: _resource_record_pre_action 11:15:01 INFO - Running main action method: download_and_extract 11:15:01 INFO - mkdir: /builds/slave/test/build/tests 11:15:01 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')]} 11:15:01 INFO - trying https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.test_packages.json 11:15:01 INFO - Downloading https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:15:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:15:01 INFO - Downloaded 989 bytes. 11:15:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:15:01 INFO - Using the following test package requirements: 11:15:01 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:15:01 INFO - u'common': [u'target.common.tests.zip'], 11:15:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:15:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:15:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:15:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:15:01 INFO - u'mozbase': [u'target.common.tests.zip'], 11:15:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:15:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:15:01 INFO - u'web-platform': [u'target.common.tests.zip', 11:15:01 INFO - u'target.web-platform.tests.tar.gz'], 11:15:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:15:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:15:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.common.tests.zip 11:15:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:15:01 INFO - Fetch https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.common.tests.zip into memory 11:15:03 INFO - Content-Length response header: 54977948 11:15:03 INFO - Bytes received: 54977948 11:15:14 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:15:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.common.tests.zip 11:15:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:15:14 INFO - Fetch https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.common.tests.zip into memory 11:15:15 INFO - Content-Length response header: 54977948 11:15:15 INFO - Bytes received: 54977948 11:15:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.talos.tests.zip 11:15:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:15:27 INFO - Fetch https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.talos.tests.zip into memory 11:15:28 INFO - Content-Length response header: 14496611 11:15:28 INFO - Bytes received: 14496611 11:15: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')]} 11:15:28 INFO - trying https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.tar.bz2 11:15:28 INFO - Downloading https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:15:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:15:29 INFO - Downloaded 61154343 bytes. 11:15:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.tar.bz2 11:15:29 INFO - mkdir: /builds/slave/test/properties 11:15:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:15:29 INFO - Writing to file /builds/slave/test/properties/build_url 11:15:29 INFO - Contents: 11:15:29 INFO - build_url:https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.tar.bz2 11:15:29 INFO - Running post-action listener: _resource_record_post_action 11:15:29 INFO - Running post-action listener: find_tests_for_verification 11:15:29 INFO - Running post-action listener: set_extra_try_arguments 11:15:29 INFO - [mozharness: 2017-08-28 18:15:29.601971Z] Finished download-and-extract step (success) 11:15:29 INFO - [mozharness: 2017-08-28 18:15:29.602068Z] Running populate-webroot step. 11:15:29 INFO - Running pre-action listener: _resource_record_pre_action 11:15:29 INFO - Running main action method: populate_webroot 11:15:29 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:15:29 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:15:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:15:29 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:15:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:15:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:15:29 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:15:29 INFO - 'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:15:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:15:29 INFO - 'tests': ['damp', 'tps']}, 11:15:29 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:15:29 INFO - 'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['damp', 'tps']}, 11:15:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:15:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:15:29 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:15:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:15:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:15:29 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:15:29 INFO - 'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:15:29 INFO - 'other-e10s': {'tests': ['a11yr', 11:15:29 INFO - 'ts_paint', 11:15:29 INFO - 'tpaint', 11:15:29 INFO - 'sessionrestore', 11:15:29 INFO - 'sessionrestore_many_windows', 11:15:29 INFO - 'sessionrestore_no_auto_restore', 11:15:29 INFO - 'tabpaint']}, 11:15:29 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['a11yr', 11:15:29 INFO - 'ts_paint', 11:15:29 INFO - 'tpaint', 11:15:29 INFO - 'sessionrestore', 11:15:29 INFO - 'sessionrestore_many_windows', 11:15:29 INFO - 'sessionrestore_no_auto_restore', 11:15:29 INFO - 'tabpaint']}, 11:15:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:15:29 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:15:29 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['bloom_basic_singleton']}, 11:15:29 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['bloom_basic']}, 11:15:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:15:29 INFO - 'tsvgr_opacity', 11:15:29 INFO - 'tart', 11:15:29 INFO - 'tscrollx', 11:15:29 INFO - 'tsvg_static']}, 11:15:29 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['tsvgx', 11:15:29 INFO - 'tsvgr_opacity', 11:15:29 INFO - 'tart', 11:15:29 INFO - 'tscrollx', 11:15:29 INFO - 'tsvg_static']}, 11:15:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:15:29 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:15:29 INFO - 'talos_options': ['--stylo'], 11:15:29 INFO - 'tests': ['tp5o']}, 11:15:29 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:15:29 INFO - 'talos_options': ['--mitmproxy', 11:15:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:15:29 INFO - '--firstNonBlankPaint'], 11:15:29 INFO - 'tests': ['tp6_google', 11:15:29 INFO - 'tp6_youtube', 11:15:29 INFO - 'tp6_amazon', 11:15:29 INFO - 'tp6_facebook']}, 11:15:29 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:15:29 INFO - 'talos_options': ['--stylo', 11:15:29 INFO - '--mitmproxy', 11:15:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:15:29 INFO - '--firstNonBlankPaint'], 11:15:29 INFO - 'tests': ['tp6_google', 11:15:29 INFO - 'tp6_youtube', 11:15:29 INFO - 'tp6_amazon', 11:15:29 INFO - 'tp6_facebook']}, 11:15:29 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:15:29 INFO - 'talos_options': ['--stylo', 11:15:29 INFO - '--stylo-threads=1', 11:15:29 INFO - '--mitmproxy', 11:15:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:15:29 INFO - '--firstNonBlankPaint'], 11:15:29 INFO - 'tests': ['tp6_google', 11:15:29 INFO - 'tp6_youtube', 11:15:29 INFO - 'tp6_amazon', 11:15:29 INFO - 'tp6_facebook']}, 11:15:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:15:29 INFO - 'talos_options': ['--xperf_path', 11:15:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:15:29 INFO - 'tests': ['tp5n']}, 11:15:29 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:15:29 INFO - 'talos_options': ['--stylo', 11:15:29 INFO - '--xperf_path', 11:15:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:15:29 INFO - 'tests': ['tp5n']}}} 11:15:29 INFO - Running post-action listener: _resource_record_post_action 11:15:29 INFO - [mozharness: 2017-08-28 18:15:29.616164Z] Finished populate-webroot step (success) 11:15:29 INFO - [mozharness: 2017-08-28 18:15:29.616253Z] Running create-virtualenv step. 11:15:29 INFO - Running pre-action listener: _resource_record_pre_action 11:15:29 INFO - Running main action method: create_virtualenv 11:15:29 INFO - Creating virtualenv /builds/slave/test/build/venv 11:15:29 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:15:29 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:15:29 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:15:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:15:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:15:30 INFO - Installing distribute.............................................................................................................................................................................................done. 11:15:30 INFO - Installing pip...............done. 11:15:30 INFO - Return code: 0 11:15:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:15:30 INFO - Reading from file tmpfile_stdout 11:15:30 INFO - Output received: 11:15:30 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:15:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2865f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', '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 11:15:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:15:30 INFO - Using env: {'DISPLAY': ':0', 11:15:30 INFO - 'HOME': '/home/cltbld', 11:15:30 INFO - 'LANG': 'en_US.UTF-8', 11:15:30 INFO - 'LANGUAGE': 'en_US:en', 11:15:30 INFO - 'LOGNAME': 'cltbld', 11:15:30 INFO - 'MAIL': '/var/mail/cltbld', 11:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:30 INFO - 'MOZ_NO_REMOTE': '1', 11:15:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:30 INFO - 'NO_EM_RESTART': '1', 11:15:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:30 INFO - 'PWD': '/builds/slave/test', 11:15:30 INFO - 'SHELL': '/bin/bash', 11:15:30 INFO - 'SHLVL': '1', 11:15:30 INFO - 'TERM': 'linux', 11:15:30 INFO - 'TMOUT': '86400', 11:15:30 INFO - 'USER': 'cltbld', 11:15:30 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:30 INFO - '_': '/tools/buildbot/bin/python'} 11:15:31 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:15:31 INFO - Downloading/unpacking pip>=1.5 11:15:31 INFO - Running setup.py egg_info for package pip 11:15:31 INFO - warning: no files found matching 'pip/cacert.pem' 11:15:31 INFO - warning: no files found matching '*.html' under directory 'docs' 11:15:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:15:31 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:15:31 INFO - Installing collected packages: pip 11:15:31 INFO - Found existing installation: pip 1.1 11:15:31 INFO - Uninstalling pip: 11:15:31 INFO - Successfully uninstalled pip 11:15:31 INFO - Running setup.py install for pip 11:15:31 INFO - warning: no files found matching 'pip/cacert.pem' 11:15:31 INFO - warning: no files found matching '*.html' under directory 'docs' 11:15:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:15:31 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:15:31 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:15:31 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:15:31 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:15:31 INFO - Successfully installed pip 11:15:31 INFO - Cleaning up... 11:15:31 INFO - Return code: 0 11:15:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:15: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')]} 11:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15: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')]} 11:15:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15: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', '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 0x24f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2865f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', '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 11:15: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:15: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 psutil>=3.1.1 11:15:31 INFO - Using env: {'DISPLAY': ':0', 11:15:31 INFO - 'HOME': '/home/cltbld', 11:15:31 INFO - 'LANG': 'en_US.UTF-8', 11:15:31 INFO - 'LANGUAGE': 'en_US:en', 11:15:31 INFO - 'LOGNAME': 'cltbld', 11:15:31 INFO - 'MAIL': '/var/mail/cltbld', 11:15:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:31 INFO - 'MOZ_NO_REMOTE': '1', 11:15:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:31 INFO - 'NO_EM_RESTART': '1', 11:15:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:31 INFO - 'PWD': '/builds/slave/test', 11:15:31 INFO - 'SHELL': '/bin/bash', 11:15:31 INFO - 'SHLVL': '1', 11:15:31 INFO - 'TERM': 'linux', 11:15:31 INFO - 'TMOUT': '86400', 11:15:31 INFO - 'USER': 'cltbld', 11:15:31 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:15:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:31 INFO - '_': '/tools/buildbot/bin/python'} 11:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:32 INFO - Downloading/unpacking psutil>=3.1.1 11:15: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 11:15: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 11:15: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 11:15: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 11:15:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:15:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:33 INFO - Installing collected packages: psutil 11:15:33 INFO - Running setup.py install for psutil 11:15:33 INFO - building 'psutil._psutil_linux' extension 11:15:33 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 11:15:33 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 11:15:33 INFO - building 'psutil._psutil_posix' extension 11:15:33 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 11:15:33 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 11:15:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:33 INFO - Successfully installed psutil 11:15:33 INFO - Cleaning up... 11:15:33 INFO - Return code: 0 11:15:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:15: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')]} 11:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15: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')]} 11:15:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2865f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', '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 11:15: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:15: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 mozsystemmonitor==0.3 11:15:33 INFO - Using env: {'DISPLAY': ':0', 11:15:33 INFO - 'HOME': '/home/cltbld', 11:15:33 INFO - 'LANG': 'en_US.UTF-8', 11:15:33 INFO - 'LANGUAGE': 'en_US:en', 11:15:33 INFO - 'LOGNAME': 'cltbld', 11:15:33 INFO - 'MAIL': '/var/mail/cltbld', 11:15:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:33 INFO - 'MOZ_NO_REMOTE': '1', 11:15:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:33 INFO - 'NO_EM_RESTART': '1', 11:15:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:33 INFO - 'PWD': '/builds/slave/test', 11:15:33 INFO - 'SHELL': '/bin/bash', 11:15:33 INFO - 'SHLVL': '1', 11:15:33 INFO - 'TERM': 'linux', 11:15:33 INFO - 'TMOUT': '86400', 11:15:33 INFO - 'USER': 'cltbld', 11:15:33 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:15:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:33 INFO - '_': '/tools/buildbot/bin/python'} 11:15:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:15: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 11:15: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 11:15: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 11:15: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 11:15:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:15:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:15:35 INFO - Installing collected packages: mozsystemmonitor 11:15:35 INFO - Running setup.py install for mozsystemmonitor 11:15:35 INFO - Successfully installed mozsystemmonitor 11:15:35 INFO - Cleaning up... 11:15:35 INFO - Return code: 0 11:15:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:15: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')]} 11:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15: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')]} 11:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15: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', '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 0x24f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2865f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', '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 11:15: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:15: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 jsonschema==2.5.1 11:15:35 INFO - Using env: {'DISPLAY': ':0', 11:15:35 INFO - 'HOME': '/home/cltbld', 11:15:35 INFO - 'LANG': 'en_US.UTF-8', 11:15:35 INFO - 'LANGUAGE': 'en_US:en', 11:15:35 INFO - 'LOGNAME': 'cltbld', 11:15:35 INFO - 'MAIL': '/var/mail/cltbld', 11:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:35 INFO - 'MOZ_NO_REMOTE': '1', 11:15:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:35 INFO - 'NO_EM_RESTART': '1', 11:15:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:35 INFO - 'PWD': '/builds/slave/test', 11:15:35 INFO - 'SHELL': '/bin/bash', 11:15:35 INFO - 'SHLVL': '1', 11:15:35 INFO - 'TERM': 'linux', 11:15:35 INFO - 'TMOUT': '86400', 11:15:35 INFO - 'USER': 'cltbld', 11:15:35 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:35 INFO - '_': '/tools/buildbot/bin/python'} 11:15:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:35 INFO - Downloading/unpacking jsonschema==2.5.1 11:15: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 11:15: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 11:15: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 11:15: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 11:15:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:15:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:15: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 11:15: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 11:15: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 11:15: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 11:15:36 INFO - Downloading functools32-3.2.3-2.tar.gz 11:15:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:15:36 INFO - warning: no files found matching '*.txt' 11:15:36 INFO - no previously-included directories found matching 'build' 11:15:36 INFO - no previously-included directories found matching 'dist' 11:15:36 INFO - no previously-included directories found matching '.git*' 11:15:36 INFO - Installing collected packages: jsonschema, functools32 11:15:36 INFO - Running setup.py install for functools32 11:15:36 INFO - warning: no files found matching '*.txt' 11:15:36 INFO - no previously-included directories found matching 'build' 11:15:36 INFO - no previously-included directories found matching 'dist' 11:15:36 INFO - no previously-included directories found matching '.git*' 11:15:36 INFO - Successfully installed jsonschema functools32 11:15:36 INFO - Cleaning up... 11:15:36 INFO - Return code: 0 11:15:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:15: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')]} 11:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15: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')]} 11:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2865f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', '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 11:15:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:15:36 INFO - Using env: {'DISPLAY': ':0', 11:15:36 INFO - 'HOME': '/home/cltbld', 11:15:36 INFO - 'LANG': 'en_US.UTF-8', 11:15:36 INFO - 'LANGUAGE': 'en_US:en', 11:15:36 INFO - 'LOGNAME': 'cltbld', 11:15:36 INFO - 'MAIL': '/var/mail/cltbld', 11:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:36 INFO - 'MOZ_NO_REMOTE': '1', 11:15:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:36 INFO - 'NO_EM_RESTART': '1', 11:15:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:36 INFO - 'PWD': '/builds/slave/test', 11:15:36 INFO - 'SHELL': '/bin/bash', 11:15:36 INFO - 'SHLVL': '1', 11:15:36 INFO - 'TERM': 'linux', 11:15:36 INFO - 'TMOUT': '86400', 11:15:36 INFO - 'USER': 'cltbld', 11:15:36 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:36 INFO - '_': '/tools/buildbot/bin/python'} 11:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:15:37 INFO - Cleaning up... 11:15:37 INFO - Return code: 0 11:15:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:15: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')]} 11:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15: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')]} 11:15:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2865f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', '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 11:15:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:15:37 INFO - Using env: {'DISPLAY': ':0', 11:15:37 INFO - 'HOME': '/home/cltbld', 11:15:37 INFO - 'LANG': 'en_US.UTF-8', 11:15:37 INFO - 'LANGUAGE': 'en_US:en', 11:15:37 INFO - 'LOGNAME': 'cltbld', 11:15:37 INFO - 'MAIL': '/var/mail/cltbld', 11:15:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:37 INFO - 'MOZ_NO_REMOTE': '1', 11:15:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:37 INFO - 'NO_EM_RESTART': '1', 11:15:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:37 INFO - 'PWD': '/builds/slave/test', 11:15:37 INFO - 'SHELL': '/bin/bash', 11:15:37 INFO - 'SHLVL': '1', 11:15:37 INFO - 'TERM': 'linux', 11:15:37 INFO - 'TMOUT': '86400', 11:15:37 INFO - 'USER': 'cltbld', 11:15:37 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:15:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:37 INFO - '_': '/tools/buildbot/bin/python'} 11:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:37 INFO - Downloading/unpacking blobuploader==1.2.4 11:15: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 11:15: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 11:15: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 11:15: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 11:15:38 INFO - Downloading blobuploader-1.2.4.tar.gz 11:15:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:15:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:15:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:39 INFO - Downloading docopt-0.6.1.tar.gz 11:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:15:39 INFO - Installing collected packages: blobuploader, requests, docopt 11:15:39 INFO - Running setup.py install for blobuploader 11:15:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:15:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:15:39 INFO - Running setup.py install for requests 11:15:39 INFO - Running setup.py install for docopt 11:15:39 INFO - Successfully installed blobuploader requests docopt 11:15:39 INFO - Cleaning up... 11:15:39 INFO - Return code: 0 11:15:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:39 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')]} 11:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:39 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')]} 11:15:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:39 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 0x24f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2865f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', '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 11:15:39 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 11:15:39 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 11:15:39 INFO - Using env: {'DISPLAY': ':0', 11:15:39 INFO - 'HOME': '/home/cltbld', 11:15:39 INFO - 'LANG': 'en_US.UTF-8', 11:15:39 INFO - 'LANGUAGE': 'en_US:en', 11:15:39 INFO - 'LOGNAME': 'cltbld', 11:15:39 INFO - 'MAIL': '/var/mail/cltbld', 11:15:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:39 INFO - 'MOZ_NO_REMOTE': '1', 11:15:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:39 INFO - 'NO_EM_RESTART': '1', 11:15:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:39 INFO - 'PWD': '/builds/slave/test', 11:15:39 INFO - 'SHELL': '/bin/bash', 11:15:39 INFO - 'SHLVL': '1', 11:15:39 INFO - 'TERM': 'linux', 11:15:39 INFO - 'TMOUT': '86400', 11:15:39 INFO - 'USER': 'cltbld', 11:15:39 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:15:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:39 INFO - '_': '/tools/buildbot/bin/python'} 11:15:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:15:39 INFO - Running setup.py (path:/tmp/pip-uXN6Fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:15:39 INFO - Running setup.py (path:/tmp/pip-2wG7UZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:15:40 INFO - Running setup.py (path:/tmp/pip-jgU7VP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:15:40 INFO - Running setup.py (path:/tmp/pip-ZMJtky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:15:40 INFO - Running setup.py (path:/tmp/pip-kZTefk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:15:40 INFO - Running setup.py (path:/tmp/pip-NNIK9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:15:40 INFO - Running setup.py (path:/tmp/pip-VDrsF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:15:40 INFO - Running setup.py (path:/tmp/pip-TVJ1cQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:15:40 INFO - Running setup.py (path:/tmp/pip-taAGo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:15:40 INFO - Running setup.py (path:/tmp/pip-l05ZQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:15:40 INFO - Running setup.py (path:/tmp/pip-ABqc41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:15:40 INFO - Running setup.py (path:/tmp/pip-SiGugH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:15:40 INFO - Running setup.py (path:/tmp/pip-lHm8Wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:15:40 INFO - Running setup.py (path:/tmp/pip-qW_9sq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:40 INFO - Running setup.py (path:/tmp/pip-CwpYNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:15:40 INFO - Running setup.py (path:/tmp/pip-BljmzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:15:41 INFO - Running setup.py (path:/tmp/pip-2G9XYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:15:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:15:41 INFO - Running setup.py install for manifestparser 11:15:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:15:41 INFO - Running setup.py install for mozcrash 11:15:41 INFO - Running setup.py install for mozdebug 11:15:41 INFO - Running setup.py install for mozdevice 11:15:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:15:41 INFO - Running setup.py install for mozfile 11:15:41 INFO - Running setup.py install for mozhttpd 11:15:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:15:41 INFO - Running setup.py install for mozinfo 11:15:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:15:41 INFO - Running setup.py install for mozInstall 11:15:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:15:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:15:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:15:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:15:41 INFO - Running setup.py install for mozleak 11:15:42 INFO - Running setup.py install for mozlog 11:15:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:15:42 INFO - Running setup.py install for moznetwork 11:15:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:15:42 INFO - Running setup.py install for mozprocess 11:15:42 INFO - Running setup.py install for mozprofile 11:15:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:15:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:15:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:15:42 INFO - Running setup.py install for mozrunner 11:15:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:15:42 INFO - Running setup.py install for mozscreenshot 11:15:42 INFO - Running setup.py install for moztest 11:15:42 INFO - Running setup.py install for mozversion 11:15:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:15:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:15:42 INFO - Cleaning up... 11:15:42 INFO - Return code: 0 11:15:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:42 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 0x24f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2865f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', '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 11:15:42 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 11:15:42 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 11:15:42 INFO - Using env: {'DISPLAY': ':0', 11:15:42 INFO - 'HOME': '/home/cltbld', 11:15:42 INFO - 'LANG': 'en_US.UTF-8', 11:15:42 INFO - 'LANGUAGE': 'en_US:en', 11:15:42 INFO - 'LOGNAME': 'cltbld', 11:15:42 INFO - 'MAIL': '/var/mail/cltbld', 11:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:42 INFO - 'MOZ_NO_REMOTE': '1', 11:15:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:42 INFO - 'NO_EM_RESTART': '1', 11:15:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:42 INFO - 'PWD': '/builds/slave/test', 11:15:42 INFO - 'SHELL': '/bin/bash', 11:15:42 INFO - 'SHLVL': '1', 11:15:42 INFO - 'TERM': 'linux', 11:15:42 INFO - 'TMOUT': '86400', 11:15:42 INFO - 'USER': 'cltbld', 11:15:42 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:42 INFO - '_': '/tools/buildbot/bin/python'} 11:15:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:15:43 INFO - Running setup.py (path:/tmp/pip-gASg3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:15:43 INFO - Running setup.py (path:/tmp/pip-rJo8O5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:15:43 INFO - Running setup.py (path:/tmp/pip-uovJnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:15:43 INFO - Running setup.py (path:/tmp/pip-tChODW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:15:43 INFO - Running setup.py (path:/tmp/pip-WgglwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:15:43 INFO - Running setup.py (path:/tmp/pip-Rc_ia5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:15:43 INFO - Running setup.py (path:/tmp/pip-eFvtPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:15:43 INFO - Running setup.py (path:/tmp/pip-4zGRDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:15:43 INFO - Running setup.py (path:/tmp/pip-i90Cu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:15:43 INFO - Running setup.py (path:/tmp/pip-mEf6oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:15:43 INFO - Running setup.py (path:/tmp/pip-s5WV2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:15:43 INFO - Running setup.py (path:/tmp/pip-9n1ijq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:15:43 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)) 11:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:15:43 INFO - Running setup.py (path:/tmp/pip-yUFR2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:15:44 INFO - Running setup.py (path:/tmp/pip-QjulI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:15:44 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)) 11:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:44 INFO - Running setup.py (path:/tmp/pip-ZHZucK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:44 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)) 11:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:15:44 INFO - Running setup.py (path:/tmp/pip-Hora0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:15:44 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)) 11:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:15:44 INFO - Running setup.py (path:/tmp/pip-HDOqV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:15:44 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)) 11:15:44 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)) 11:15:44 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)) 11:15:44 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)) 11:15:44 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)) 11:15:44 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)) 11:15:44 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)) 11:15:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:15: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 11:15: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 11:15: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 11:15: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 11:15:45 INFO - Downloading blessings-1.6.tar.gz 11:15:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:15:45 INFO - Installing collected packages: blessings 11:15:45 INFO - Running setup.py install for blessings 11:15:45 INFO - Successfully installed blessings 11:15:45 INFO - Cleaning up... 11:15:45 INFO - Return code: 0 11:15:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:15:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:15:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:15:45 INFO - Reading from file tmpfile_stdout 11:15:45 INFO - Current package versions: 11:15:45 INFO - argparse == 1.2.1 11:15:45 INFO - blessings == 1.6 11:15:45 INFO - blobuploader == 1.2.4 11:15:45 INFO - docopt == 0.6.1 11:15:45 INFO - functools32 == 3.2.3-2 11:15:45 INFO - jsonschema == 2.5.1 11:15:45 INFO - manifestparser == 1.1 11:15:45 INFO - mozInstall == 1.13 11:15:45 INFO - mozcrash == 1.0 11:15:45 INFO - mozdebug == 0.1 11:15:45 INFO - mozdevice == 0.51 11:15:45 INFO - mozfile == 1.2 11:15:45 INFO - mozhttpd == 0.7 11:15:45 INFO - mozinfo == 0.10 11:15:45 INFO - mozleak == 0.1 11:15:45 INFO - mozlog == 3.5 11:15:45 INFO - moznetwork == 0.27 11:15:45 INFO - mozprocess == 0.25 11:15:45 INFO - mozprofile == 0.28 11:15:45 INFO - mozrunner == 6.13 11:15:45 INFO - mozscreenshot == 0.1 11:15:45 INFO - mozsystemmonitor == 0.3 11:15:45 INFO - moztest == 0.8 11:15:45 INFO - mozversion == 1.4 11:15:45 INFO - psutil == 3.1.1 11:15:45 INFO - requests == 1.2.3 11:15:45 INFO - wsgiref == 0.1.2 11:15:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:45 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')]} 11:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:45 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')]} 11:15:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:45 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 0x24f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2865f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', '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 11:15:45 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 11:15:45 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 11:15:45 INFO - Using env: {'DISPLAY': ':0', 11:15:45 INFO - 'HOME': '/home/cltbld', 11:15:45 INFO - 'LANG': 'en_US.UTF-8', 11:15:45 INFO - 'LANGUAGE': 'en_US:en', 11:15:45 INFO - 'LOGNAME': 'cltbld', 11:15:45 INFO - 'MAIL': '/var/mail/cltbld', 11:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:45 INFO - 'MOZ_NO_REMOTE': '1', 11:15:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:45 INFO - 'NO_EM_RESTART': '1', 11:15:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:45 INFO - 'PWD': '/builds/slave/test', 11:15:45 INFO - 'SHELL': '/bin/bash', 11:15:45 INFO - 'SHLVL': '1', 11:15:45 INFO - 'TERM': 'linux', 11:15:45 INFO - 'TMOUT': '86400', 11:15:45 INFO - 'USER': 'cltbld', 11:15:45 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:45 INFO - '_': '/tools/buildbot/bin/python'} 11:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:46 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)) 11:15:46 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)) 11:15:46 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)) 11:15:46 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)) 11:15:46 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)) 11:15:46 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)) 11:15:46 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)) 11:15:46 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)) 11:15:46 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)) 11:15:46 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:15:46 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 11:15:46 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 11:15:46 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 11:15:46 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 11:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:15:47 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)) 11:15:47 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)) 11:15:47 INFO - Installing collected packages: simplejson 11:15:47 INFO - Running setup.py install for simplejson 11:15:47 INFO - building 'simplejson._speedups' extension 11:15:47 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 11:15:48 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 11:15:48 INFO - Successfully installed simplejson 11:15:48 INFO - Cleaning up... 11:15:48 INFO - Return code: 0 11:15:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:48 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 0x24f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2865f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', '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 11:15:48 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 11:15:48 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 11:15:48 INFO - Using env: {'DISPLAY': ':0', 11:15:48 INFO - 'HOME': '/home/cltbld', 11:15:48 INFO - 'LANG': 'en_US.UTF-8', 11:15:48 INFO - 'LANGUAGE': 'en_US:en', 11:15:48 INFO - 'LOGNAME': 'cltbld', 11:15:48 INFO - 'MAIL': '/var/mail/cltbld', 11:15:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:48 INFO - 'MOZ_NO_REMOTE': '1', 11:15:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:48 INFO - 'NO_EM_RESTART': '1', 11:15:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:48 INFO - 'PWD': '/builds/slave/test', 11:15:48 INFO - 'SHELL': '/bin/bash', 11:15:48 INFO - 'SHLVL': '1', 11:15:48 INFO - 'TERM': 'linux', 11:15:48 INFO - 'TMOUT': '86400', 11:15:48 INFO - 'USER': 'cltbld', 11:15:48 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:15:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:48 INFO - '_': '/tools/buildbot/bin/python'} 11:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:15:48 INFO - Cleaning up... 11:15:48 INFO - Return code: 0 11:15:48 INFO - Running post-action listener: _resource_record_post_action 11:15:48 INFO - Running post-action listener: _start_resource_monitoring 11:15:48 INFO - Starting resource monitoring. 11:15:48 INFO - [mozharness: 2017-08-28 18:15:48.570676Z] Finished create-virtualenv step (success) 11:15:48 INFO - [mozharness: 2017-08-28 18:15:48.571154Z] Running install step. 11:15:48 INFO - Running pre-action listener: _resource_record_pre_action 11:15:48 INFO - Running main action method: install 11:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:15:48 INFO - Reading from file tmpfile_stdout 11:15:48 INFO - Detecting whether we're running mozinstall >=1.0... 11:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:15:48 INFO - Reading from file tmpfile_stdout 11:15:48 INFO - Output received: 11:15:48 INFO - Usage: mozinstall [options] installer 11:15:48 INFO - Options: 11:15:48 INFO - -h, --help show this help message and exit 11:15:48 INFO - -d DEST, --destination=DEST 11:15:48 INFO - Directory to install application into. [default: 11:15:48 INFO - "/builds/slave/test"] 11:15:48 INFO - --app=APP Application being installed. [default: firefox] 11:15:48 INFO - mkdir: /builds/slave/test/build/application 11:15:48 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'] 11:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:16:06 INFO - Reading from file tmpfile_stdout 11:16:06 INFO - Output received: 11:16:06 INFO - /builds/slave/test/build/application/firefox/firefox 11:16:06 INFO - Running post-action listener: _resource_record_post_action 11:16:06 INFO - [mozharness: 2017-08-28 18:16:06.637783Z] Finished install step (success) 11:16:06 INFO - [mozharness: 2017-08-28 18:16:06.638064Z] Running setup-mitmproxy step. 11:16:06 INFO - Running pre-action listener: _resource_record_pre_action 11:16:06 INFO - Running main action method: setup_mitmproxy 11:16:06 INFO - Skipping: mitmproxy is not required 11:16:06 INFO - Running post-action listener: _resource_record_post_action 11:16:06 INFO - [mozharness: 2017-08-28 18:16:06.639192Z] Finished setup-mitmproxy step (success) 11:16:06 INFO - [mozharness: 2017-08-28 18:16:06.639412Z] Running run-tests step. 11:16:06 INFO - Running pre-action listener: _resource_record_pre_action 11:16:06 INFO - Running pre-action listener: _set_gcov_prefix 11:16:06 INFO - Running main action method: run_tests 11:16:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:16:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:16:06 INFO - Python 2.7.3 11:16:06 INFO - Return code: 0 11:16:06 INFO - grabbing minidump binary from tooltool 11:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:06 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2863130>, '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 0x2864030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28644c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:16:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:16:06 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:16:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:16:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:16:06 INFO - Return code: 0 11:16:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:16:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:16:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:16:06 INFO - ENV: RUST_BACKTRACE is now full 11:16:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:16:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:16:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:16:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-087 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:16:06 INFO - Using env: {'DISPLAY': ':0', 11:16:06 INFO - 'HOME': '/home/cltbld', 11:16:06 INFO - 'LANG': 'en_US.UTF-8', 11:16:06 INFO - 'LANGUAGE': 'en_US:en', 11:16:06 INFO - 'LOGNAME': 'cltbld', 11:16:06 INFO - 'MAIL': '/var/mail/cltbld', 11:16:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:16:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:06 INFO - 'MOZ_NO_REMOTE': '1', 11:16:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:16:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:06 INFO - 'NO_EM_RESTART': '1', 11:16:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:06 INFO - 'PWD': '/builds/slave/test', 11:16:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:16:06 INFO - 'RUST_BACKTRACE': 'full', 11:16:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:16:06 INFO - 'SHELL': '/bin/bash', 11:16:06 INFO - 'SHLVL': '1', 11:16:06 INFO - 'STYLO_FORCE_ENABLED': '1', 11:16:06 INFO - 'TERM': 'linux', 11:16:06 INFO - 'TMOUT': '86400', 11:16:06 INFO - 'USER': 'cltbld', 11:16:06 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:06 INFO - '_': '/tools/buildbot/bin/python'} 11:16:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:16:07 INFO - mozversion application_buildid: 20170828172502 11:16:07 INFO - mozversion application_changeset: 3529b653ede26f990eb7320649015294ad0f8e76 11:16:07 INFO - mozversion application_display_name: Nightly 11:16:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:16:07 INFO - mozversion application_name: Firefox 11:16:07 INFO - mozversion application_remotingname: firefox 11:16:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:16:07 INFO - mozversion application_vendor: Mozilla 11:16:07 INFO - mozversion application_version: 57.0a1 11:16:07 INFO - mozversion platform_buildid: 20170828172502 11:16:07 INFO - mozversion platform_changeset: 3529b653ede26f990eb7320649015294ad0f8e76 11:16:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:16:07 INFO - mozversion platform_version: 57.0a1 11:16:07 INFO - using testdate: 1503944167 11:16:07 INFO - actual date: 1503944167 11:16:07 INFO - starting webserver on 'localhost:51983' 11:16:07 INFO - SUITE-START | Running 2 tests 11:16:07 INFO - TEST-START | dromaeo_css 11:16:07 INFO - Initialising browser for dromaeo_css test... 11:16:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51983/getInfo.html -profile /tmp/tmpY062je/profile 11:16:07 INFO - TEST-INFO | started process 1393 (/builds/slave/test/build/application/firefox/firefox http://localhost:51983/getInfo.html) 11:16:12 INFO - TEST-INFO | 1393: exit 0 11:16:13 INFO - Browser initialized. 11:16:13 INFO - Running cycle 1/1 for dromaeo_css test... 11:16:13 INFO - Using env: {'DISPLAY': ':0', 11:16:13 INFO - 'HOME': '/home/cltbld', 11:16:13 INFO - 'JSGC_DISABLE_POISONING': '1', 11:16:13 INFO - 'LANG': 'en_US.UTF-8', 11:16:13 INFO - 'LANGUAGE': 'en_US:en', 11:16:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:16:13 INFO - 'LOGNAME': 'cltbld', 11:16:13 INFO - 'MAIL': '/var/mail/cltbld', 11:16:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:16:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:16:13 INFO - 'MOZ_CRASHREPORTER': '1', 11:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:16:13 INFO - 'MOZ_NO_REMOTE': '1', 11:16:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:13 INFO - 'NO_EM_RESTART': '1', 11:16:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:13 INFO - 'PWD': '/builds/slave/test', 11:16:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:16:13 INFO - 'RUST_BACKTRACE': 'full', 11:16:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:16:13 INFO - 'SHELL': '/bin/bash', 11:16:13 INFO - 'SHLVL': '1', 11:16:13 INFO - 'STYLO_FORCE_ENABLED': '1', 11:16:13 INFO - 'TERM': 'linux', 11:16:13 INFO - 'TMOUT': '86400', 11:16:13 INFO - 'USER': 'cltbld', 11:16:13 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:13 INFO - '_': '/tools/buildbot/bin/python'} 11:16:13 INFO - TEST-INFO | started process 1580 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY062je/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:16:14 INFO - PID 1580 | 11:16:14 INFO - PID 1580 | (/builds/slave/test/build/application/firefox/firefox:1629): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:16:14 INFO - PID 1580 | 11:16:24 INFO - PID 1580 | RSS: Main: 169652224 11:16:24 INFO - PID 1580 | 11:19:23 INFO - PID 1580 | Cycle 1(1): loaded http://localhost:51983/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51983/tests/dromaeo/cssquery-ext.html) 11:19:24 INFO - PID 1580 | RSS: Main: 176230400 11:19:24 INFO - PID 1580 | 11:22:26 INFO - PID 1580 | Cycle 1(1): loaded http://localhost:51983/tests/dromaeo/cssquery-ext.html (next: http://localhost:51983/tests/dromaeo/cssquery-jquery.html) 11:22:26 INFO - PID 1580 | RSS: Main: 172388352 11:22:26 INFO - PID 1580 | 11:25:28 INFO - PID 1580 | Cycle 1(1): loaded http://localhost:51983/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51983/tests/dromaeo/cssquery-mootools.html) 11:25:29 INFO - PID 1580 | RSS: Main: 174870528 11:25:29 INFO - PID 1580 | 11:28:30 INFO - PID 1580 | Cycle 1(1): loaded http://localhost:51983/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51983/tests/dromaeo/cssquery-prototype.html) 11:28:31 INFO - PID 1580 | RSS: Main: 173514752 11:28:31 INFO - PID 1580 | 11:31:35 INFO - PID 1580 | Cycle 1(1): loaded http://localhost:51983/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51983/tests/dromaeo/cssquery-yui.html) 11:31:36 INFO - PID 1580 | RSS: Main: 174977024 11:31:36 INFO - PID 1580 | 11:34:37 INFO - PID 1580 | Cycle 1(1): loaded http://localhost:51983/tests/dromaeo/cssquery-yui.html (next: http://localhost:51983/tests/dromaeo/cssquery-dojo.html) 11:34:37 INFO - PID 1580 | RSS: Main: 177360896 11:34:37 INFO - PID 1580 | 11:34:37 INFO - PID 1580 | __start_tp_report 11:34:37 INFO - PID 1580 | _x_x_mozilla_page_load 11:34:37 INFO - PID 1580 | _x_x_mozilla_page_load_details 11:34:37 INFO - PID 1580 | |i|pagename|runs| 11:34:37 INFO - PID 1580 | |0;dojo.html;3336.0825772912444;3359.3617212729578;3365.242820365417;3378.763486555942;3389.4576867701157;6327.145835312232;6368.044793280998;6372.362763723631;6375.362463753628;6411.358864113587;6261.123442718023;6374.585651932633;6377.489800815935;6402.16771819664;6412.198475190601;5853.356130825595;5867.7066146692705;5878.88242235155;5886.499647529965;5899.085641725539;5883.823485295448;5903.940960590382;5904.409559044104;5907.940920590803;5910.113482977545;5933.7033148342625;5935.465808077272;5951.58338916276;5958.225430694004;5970.731317090741;5875.059990401558;5891.322497912752;5928.02187639039;5953.047512397996;6005.069214271773;5630.0991841305195;5782.190493330937;5823.213866128103;5874.794382196603;5877.089051197071;5688.41591580555;6247.531435142382;6264.966280563702;6327.27236367819;6419.133416988693;6803.795886123424;6848.1097457330225;6863;6943.79168624937;6968;6856.24581296057;6908.171019477644;7011.088558487415;7012.824679383005;7018.368346848808;6443.840108780464;6458.483321334282;6462.935370646281;6468.8059358219325;6470.805875823733;6974.163100427981;7095.82260443493;7112.075430194145;7115.324044215791;7152.9284707152265;6756.358145976124;6770;6842.418394436529;6857.417119544894;6879.690413931285;6731.932680673229;6750.797476075725;6801.829954251084;6828.6927088281145;6956.026156338116;6816.113905192294;6907.620080895498;6911.481638877104;6912.723491060402;6918.446524278046;6782.9321706783285;6865.313468653095;6870.244273129952;6979.860402792017;6986.825329366705;5297.920531192035;5373.408925018245;5398.541124004425;5412.242286079952;5436.075867102602;4719.787609557578;4773.284007398918;4837.685550439211;4843.903121937541;4863.683860549053;5821.388754180834;5901.645901245853;5977.491913187341;5982.700864956735;6042.395760423922;6806.251312355637;6828.078209441761;6831.863362732717;6843.623600702009;6848.075509806212;5911.852203694942;5915.142304365843;5928.288605367384;5975.103734439869;6020.69896505173;5895.67573783446;5985.521158307412;5991.3409524953095;6000.21997140369;6004.729787159501;6580.374864387875;6658.334166583303;6748.426383757435;6755.256921738604;6777.6272305023695;6755.392014718699;6881.552699074544;6885.173779146535;6903.275156108536;6992.650367481504;6699.162604674416;6738.898916516354;6757.459403247827;6758;6802.3537763912345;6753.223379311442;6846.041554182518;6847.897281540681;6849;6861.416779573592;6882.449404047764;6883;6892.379685828299;6975.232724400414;6982.406495447741;6553.770618028346;6643.800685979429;6651.401373876374;6654.168228971379;6655.001749737578;6624.105745724266;6642.634655094016;6643.169603799525;6645.966770166118;6653.301403352578;6501.057346684799;6547.345265473605;6561.475081993524;6574.572652777554;6579.276279609335;6643.534952553275;6692.866142677021;6728.932710672831;6795.7961261162245;6847.794566163023;6373.808785736435;6455.515836312389;6477.222733272038;6508.967455162694;6614.603123812587 11:34:37 INFO - PID 1580 | |1;ext.html;791.1969351108626;813.2071230550214;838.9328853691705;845.7166849105549;1051.9947400262997;27776.722232777665;29146;38275.61724382759;38765.61234387655;38883.22233555327;38871.611283887156;38960.80519597398;39202.8039859801;39277.41083883744;39317;7241.6379181041;7709.3447057000085;7828.843423131534;7845.293923546879;7873.015873015873;7686.423518236126;7800.7269745558915;7810.49231799934;7838.529688218697;7840.333571646417;7789.805254868617;7817.2964433201005;7846.136924938252;7854.3716502679645;7870.2916737493615;7758.418118641096;7790.337821285169;7799.844003119934;7827.608619569056;7837.059552853637;7626.923730762677;7641.23587641237;7646.235376462365;7646.623607750658;7663.501872378278;34785.65214347875;34853.82573087118;34856;34966;35007.64992350095;3175.6983086606733;3204.4071846708434;3215.581974343344;3217.48520236761;3221.2269055426764;29703.40593188124;29713.405731885457;29843.850780746176;29850.253743656365;29879.551806722917;5180.663256888291;5182.300389447414;5221.921671174898;5227.47725227478;5242.711650859202;180057;180649;180887;181095.09452452784;181375;43608.56391436108;43734.12531749411;43845.561544384786;43850;43877.56122438799;45735.313970290474;45792.08415831665;45813.31280030802;45847.31229031567;45889.31166032512;14017.439302427993;14319.928400357932;14328.928355358365;14337.211453370164;14351.425942962373;9454;9635.903640963501;9649.372790768577;10915.563377464812;11027.393493357782;12635.684107897376;12699.809502857464;12725.490980360866;12765.489380424679;12823;5099.515546023122;5116.181410974226;5129.820456284012;5138.100832354397;5171.276021357013;935.2237642756631;937.9718608441758;938.8310104181177;941.6704153546204;958.6021800952666;21951.67072493914;21957.67063494049;22245.332640020824;22254.666180007313;22853.88573057124;1059.5073290919759;1060.4591658254346;1063.622414043019;1069.9625513107003;1080.4165750494644;11697.707557311136;11768.235064720766;11870.28778273289;11881.346525940984;11885;241374;241475;242658;242809;243019;48388;48598;48609.27086093711;48637;48732.756336218095;1294.779887419141;1299.2529295655036;1301.4013553765374;1304.067591671978;1357.1314358810369;21361.67957480608;21363.67954480715;21656.37577181686;21725.348239552837;22180.223692170697;34750.652493475754;34928.65071349254;35000.64999349974;35026.474602881484;35052.12369690676;202889;203114;203355;203839;204042;32582.185445363106;32818.50772238371;33917.66082339145;35014.64985350216;35119.29761404809;42168.78915605402;42948;42975.355369670106;43168.35247471353;43410.34884476672;31346.21634459157;31352;31431.842840785648;31442.842785785924;31549.684503154676;31484.370312594077;31562;31575.684243157273;31690.366192675556;31923.521147183274;35491.645083548836;36028.27943441169;36253.45619815652;36298.8185059073;36372;30989.535156973114;31106.22234444157;31127.84436077805;31173.8441307792;31225;20090.19639214415;23221.883890581114;23261.06955721752;23831.04675812948;24077.75922240825 11:34:37 INFO - PID 1580 | |2;jquery.html;538.5637633516855;546.8578169675885;547.8082671065125;556.2518412734872;562.1904457581082;20651.896740516295;21178.364649060553;21330.253441129564;21408.036638351274;21408.46478838026;21652.133914643433;22038.559228815415;22138.88930555345;22168.002439890202;22190.778092219072;6394.392532709385;6531.24890637578;6537.673116344187;6541.836454088639;6542.084108224853;5853.8829223415505;6444.581102228363;6455.35446455353;6460.709268082947;6464.74141034358;6439.678016099199;6440.13058237139;6448.64532450715;6449.129367535393;6458.580192287486;6410.326915673833;6426.389492998158;6429.935700642981;6432.742690292406;6441.162648855666;5976.820695379146;5980.43185897339;5987.700614969278;6011.669358185298;6014.96992515039;26727.866360668268;27113.322166945785;27544.17367478979;27994.3001424964;28186.15441536757;2022.8988550572412;2060.8866512341815;2072.347210628647;2074.1081335025933;2075.5952589245103;18321.17554710028;18974;18983.525411864677;18992.62014759711;19020.42938711827;2324.2910912171783;2355.328731311568;2363.7281712602926;2375.263668262844;2476.36852602587;128825.35587322098;132626;132786.33606832;133055;136295;13362.398692058687;13871.791923121162;13880.305984700724;14340.637639424238;14484.710305793826;22124.2256521021;22160.889195554242;22208.22271220532;22226.333210003726;22250.443738906655;9671.032896710272;9701.126898579021;9736.269779766546;9939.304248702667;9981.251406144424;7131.572105673677;7450.590217538087;8904.8664270036;8949.507777072193;9009.459432434074;7813.531188128617;8496.830063398822;8499.787505312417;8535.695362252281;8854.86717699235;2530.291518374858;2547.1721690450677;2553.6041913503436;2621.252942911261;2699.3656490724666;904.7738065483629;921.1479381572153;923.086144716739;928.053385546752;934.5047125023748;18241.543961401072;18450.26198952027;18664.34674786403;18891.14989825461;18938.52653683669;959.194276807485;976.5312649928075;978.2858513285341;980.9068138526828;981.2297346582898;10190.898091019144;10193.541290641939;10361.222908281909;10499.370037797757;10552.630657926935;491451;494452;544686;546421.8752343649;549839;121885;122599.99999999821;125635;126871.36564317119;126963.36518317534;1123.8763735989035;1127.3686735428187;1162.918595698293;1166.504235699841;1170.2451918512681;19200.71198932045;19396.612067758848;19701.40895773129;20072.799272007094;20520.79479205189;57713.42286577081;58207.70896145492;59156;59158.11262831147;59987.40012599818;70960;71197.64401178168;71374;71670.92493612705;72153;12287.69280767988;14339.641508961942;14508.492202772852;14521.85478145205;14553.054051486619;22253.331000174727;22384.552308953404;22417.66373504366;22529.549409012056;22589.774102259424;16362;16622.168891555615;16625.418110366078;16646;16836.831631683526;15791.68416631638;15911.363545458053;16193;16444;16698.081605511812;18338.358157464423;18382.264709411473;18613.534661633024;18647.160877760263;18892.527686807938;14248.572542823731;14262.57212283633;14291.57125286243;14311.141331520143;14313;17081.23134458977;17352.56618584495;17655.911720441316;17868.374606888698;17967.910160449115 11:34:37 INFO - PID 1580 | |3;mootools.html;1210.406900618697;1248.9000879929606;1265.2598230035428;1271.1165739810835;1277.195366918841;14029.36867840947;14109.153450792946;14789.926050369746;14798.704025919475;14803.40786368544;14627.707445851076;14970.850291497081;14987.250637468138;15005.324760385782;15025.32386042628;2503.1113954546195;2528.190978886757;2534.2270607464716;2554.8083893708044;2556.322574517754;2286.5769832581027;2374.2402431222017;2383.904643814254;2674.7191544887796;2787.038471727261;2718.130198336533;2721.5781553859183;2723.305557082951;2727.890884364623;2728.5497892847757;2619.76422122009;2668.7331266873352;2671.19864040788;2677.973220267792;2678.1429942418436;2409.506051259488;2418.480026794248;2431.4286142756437;2433.4281443860677;2439.499902519979;14075.436982520687;14188.148711077369;14193;14210.431582736781;14242;7560.73537426193;7821.413393995473;7821.921780782176;7824;7837.843243135162;5045.117104506733;5245.370555533361;5253.264542963988;5368.785248590052;5404.1083221268445;1783.7502749615064;1818.863585231113;1852.148011914521;1855.7309190167484;1862.245790454868;24551.87724061386;24730.752692473026;24897.62653560198;24969.750302496923;25036.374090647696;6472.644004579699;6997.160340759141;7217.097862767154;7233.927660723325;7272.309130632581;15680.294386752623;16426.671466570842;16482.42311519115;16510.6697866042;16531.256093475822;7452.366548843409;7654.808629784188;7665.271799179069;7667.463277570517;7672.386209103258;6811.318868113149;7056.223815380304;7061;7101.183363913113;7105.467089968273;4661.487236403993;4793.496682848251;4988.775505102279;5006.249062640633;5070.138076527;1494.902831315961;1499.130504307499;1505.4354617018619;1517.9772303415457;1520.376645575331;806.7136166660869;811.350919264586;823.8805373220849;829.2495291760966;833.0877688930611;7581.734639287599;7964.442489025712;7972.123066462685;7989.480683755537;8071.83856322882;934.8130373925106;942.8114377124602;948.3029972969862;952.2286947572488;971.2327261463365;4383.473983122046;4390.758508282075;5269.55208807248;5379;5466.808661696901;19758.40724778259;20424;20659.8967005164;20858.582828343348;21077.894610527157;20189.394318170478;20256.98715064241;20452.079656415495;20465.795342046687;20546;10549.947250263853;10637.521311540999;10845.859121721216;10885.782284354269;10897.400642964554;7745.883811742716;7919.920800791919;8018.799530011563;8280.533970910636;8384.245417912418;18035.549111271797;18104.275828966693;18224.817751822313;18378.264869405073;18403.53991150232;18398.356057537923;18507.167177477317;18701.34545290908;18780.718289225944;18949.43151705451;9062.682806101755;11312.660620181408;11597.246178998337;11615.6515304541;11633.302001879914;10870.130389568658;12267.447964841425;12669.239845609292;12674.429650665557;12700.87299126997;10356.896431035593;10360.067593916283;11675.532978680758;11680.240784348991;11765.294082355085;10430.947845260725;10671;10770.192235582519;10789.892101078889;11077.889221107685;8970.775730606787;9133.725988220363;9213.493257870881;9220.907790922005;9226.9538652309;6601.867962640817;6643.60138391698;6660.267370589135;6664.966675166593;6761.966190169214;6588.901166482411;6603.141591593062;6628.867422651617;6655.767048153291;6721.059051732859 11:34:37 INFO - PID 1580 | |4;prototype.html;480.77403620298446;491.76677863718635;509.56177687189034;514.4135685318737;514.8995945790572;6276.968615156923;6287.685615719213;6349.333320001402;6353.523485738565;6383.170187875569;5931.5254779617635;5934.554908381867;5984.790532331369;6121.724522396492;6235;4910.238912968487;4962.6774259673;4966.726830024347;4980.676256043345;4992.575631071363;4822.445418776833;4948.204264590513;4977.402711674604;4997.275395067713;5038.345015148026;4817.639356715219;4911.778969946361;4936.259561065851;4968.577670897978;4996.150654388744;4904.558589726942;4984.90030199394;4997.750112494397;5007.84976450707;5008.12357837377;4424.867253982386;4428.291473364278;4428.557144285578;4434.800433980478;4446.110777844444;6028.5478589106;6068.787592434288;6077.298356460198;6106.175666285084;6181.350958149419;5626.324841019063;5652.5195358394585;5671.716414179275;5700.2589663343915;5717.68433784318;6452.774152904626;6475.805725828232;6507.2191337039385;6533.314002029766;6577.572457790228;4816.373871396731;4833.782479788383;4846.39420072491;4876.097921884463;4896.216605343164;6794.9660251698915;6835.453163746888;6893;6951.478639102037;6965.58686243742;4935;4953.653244272867;4967.3855996801185;4994.600431965434;4995.375578052744;5043.218301163325;5048.419431765321;5052.292679024939;5060.291559181717;5064.645474816727;5660.547156227574;5706.71466426677;5712;5715.685637289906;5743.587693168879;6444.709988050549;6507.34926507355;6514.934850651528;6526.24948130971;6612.8346791330605;1645.5392490102552;1666.5333706562183;1680.7983042035034;1736.4096207289583;1738.1135620833466;1197.26368283505;1212.660455072581;1224.6020043485726;1242.428482897877;1250.412306216077;5647.830565083054;5675.985025316036;5697.173909783059;5736.196932429463;5842.152887831241;6510.837229069217;6591.802245932629;6607.141071660653;6640.568363056386;6690.130283063268;5756.625819321815;5784.4504772046585;5830.125481177857;5850.180974663465;5874.265716785402;3512.4028915084496;3517.806520641338;3564.8574057037945;3567.9643203568153;3587.3542762302513;6530.249021362509;6536.24833144185;6556.377144171392;6576.210854697467;6580.309067547934;6874.587524748531;6967.198772141269;6988.79033628992;7006.824829379307;7049.9647501762165;6492.318306577741;6525.4453371463005;6570.747479997432;6603.966980165068;6635.966820165965;6641.966790166019;6646.667666616747;6675.598124393931;6740.191177058588;6768.1878174617395;6652.30581614447;6715.26132125456;6743.7302507901095;6776.830579235559;6795.456363491007;6557.868842623026;6582.736690532517;6611.702473388806;6614.570052946543;6642.568233064835;4982.707954341044;5006.1739812930455;5017.222330538772;5029.949700503094;5036.84889453317;6740.561863478858;6747.224069232101;6751.358620931003;6802.761903333359;6847.554908930903;6573.820608627874;6574.704138313692;6611.239707433707;6622.801315960336;6631.767888123893;6296.496280297473;6508.902366464412;6539.215294164731;6541.603177378586;6587.769428069994;6201.441870231701;6264.371263471439;6361.904571431525;6370.426661600478;6391.58454700443;6437.710303036468;6465.482761379172;6475.417212450902;6488.870222595616;6508.012628381995;6207.503399728102;6239.96880015597;6277.246730392382;6302.968485157545;6317.5893566918 11:34:37 INFO - PID 1580 | |5;yui.html;807.4832107451232;807.6850028488886;820.5815034332488;827.2058823529411;830.410408609887;16211.189440527985;16482.752758708615;16489.257983390748;16573.17134143291;16606.086665233423;15813;16427.09650969196;17046.74429883553;17097.40159094432;17150.656986860256;778.6963084397075;788.9329407000383;789.3842802613971;792.6353877216459;792.9286364227219;725.5392825555783;791.3471386106456;798.4291231769283;802.2659266770897;804.5454318310136;833.5540485840077;835.5362773660646;835.578033093287;835.7409203147014;838.3042075077701;836.7238811192323;837.815680550278;838.337713206566;838.7693384319361;838.8993320801512;609;610.1610285856947;610.2219669920842;610.9236345456818;610.9938900611025;15308.617284567863;15333.386664533406;15377.538673839803;15394.307256173499;15416.845831541654;18255.634887302178;18665.626687466174;18765.7185142223;18809.24763009478;18878.528036799053;14662.926685366612;15040.623984400368;15187.848121518753;15271.312790924436;15299.923500382538;3735.62643735627;3769.924601507982;3772.4718539404507;3772.6793222576116;3780.0549862534367;118605.40697296459;119624.40187799092;120535.39732301282;123257;123906;14845.406183752744;14926.477573284883;15036.699266014619;15150.621234469007;15238.542843714706;26147;26212.082577109708;26243.47513049728;26927.596086058726;27052.323691907466;8786.384953053353;8838.955805221061;8857.601407936658;8878.689245876363;8885.777855553533;4198.1393814268;4204.495460544754;4227.070044590185;4253.723507971972;4303.956960430418;4348.652107831429;4526.660500462479;4633.0733853229485;4722.291656251521;5340.172273297645;949.4350861237552;967.1150896929372;983.4050399508337;992.369845148336;996.1234113979651;655.7377049180366;667.219353356574;681.1757773094512;683.774354463026;692.1348314606741;11252;11558.504967167908;11966.042716582653;12030.015047517061;12317.07621928359;661.3485716569176;672.6703915081574;675.8513127112027;679.6228093408206;687.6149356360454;5576.1356989666665;5618.522425593788;5643.266375371176;5662.631928924607;5770.855728606818;110373;111167;112588.43705781583;112589;117208.41395792966;103118;104622.47688761659;105592.89462417008;108715.45642271738;109281.45359273312;84162.57918710368;86193.13806861851;86668.56665716632;88752.11247887567;89651.55174224087;10535.473226338805;10610.204234682275;10692.037716605231;10743.623973160902;10893.183011274345;41555.792221038704;41927.790361048;42219.36670949877;42509;43390.34914476222;52831.47168528265;53568.73215633897;54400;56104.43895560992;56565.43434565602;15002.549923502313;15429.845701542841;15576.532704018897;15597.376104956129;15600.92199538995;17568.472945811645;17891.73162402539;17919.552011199303;18014.2794288227;18036.54908627295;14061.507847225706;16465;16518.504444866674;16521.173941302648;16737.91631041837;15619.14094724801;15693.058416495047;15705.371785128924;15776.447824326553;15889.443869464976;16439.753403699193;16471.588210294838;16552.255148518103;16577.088260145807;16669.416570419977;12206.755864882474;12814.743705126031;12947.417366218731;13155.342232888508;13250.46998120103;12429.627111186679;12454.688632784253;12668.619941401772;12885.226886386847;13907.165570065828 11:34:37 INFO - PID 1580 | __end_tp_report 11:34:37 INFO - PID 1580 | __start_cc_report 11:34:37 INFO - PID 1580 | _x_x_mozilla_cycle_collect,389 11:34:37 INFO - PID 1580 | __end_cc_report 11:34:37 INFO - PID 1580 | __startTimestamp1503945277974__endTimestamp 11:34:37 INFO - PID 1580 | 11:34:37 INFO - PID 1580 | ------- Summary: start ------- 11:34:37 INFO - PID 1580 | Number of tests: 6 11:34:37 INFO - PID 1580 | 11:34:37 INFO - PID 1580 | [#0] dojo.html Cycles:165 Average:6321.60 Median:6561.48 stddev:738.61 (11.3%) stddev-sans-first:702.77 11:34:37 INFO - PID 1580 | Values: 3336.1 3359.4 3365.2 3378.8 3389.5 6327.1 6368.0 6372.4 6375.4 6411.4 6261.1 6374.6 6377.5 6402.2 6412.2 5853.4 5867.7 5878.9 5886.5 5899.1 5883.8 5903.9 5904.4 5907.9 5910.1 5933.7 5935.5 5951.6 5958.2 5970.7 5875.1 5891.3 5928.0 5953.0 6005.1 5630.1 5782.2 5823.2 5874.8 5877.1 5688.4 6247.5 6265.0 6327.3 6419.1 6803.8 6848.1 6863.0 6943.8 6968.0 6856.2 6908.2 7011.1 7012.8 7018.4 6443.8 6458.5 6462.9 6468.8 6470.8 6974.2 7095.8 7112.1 7115.3 7152.9 6756.4 6770.0 6842.4 6857.4 6879.7 6731.9 6750.8 6801.8 6828.7 6956.0 6816.1 6907.6 6911.5 6912.7 6918.4 6782.9 6865.3 6870.2 6979.9 6986.8 5297.9 5373.4 5398.5 5412.2 5436.1 4719.8 4773.3 4837.7 4843.9 4863.7 5821.4 5901.6 5977.5 5982.7 6042.4 6806.3 6828.1 6831.9 6843.6 6848.1 5911.9 5915.1 5928.3 5975.1 6020.7 5895.7 5985.5 5991.3 6000.2 6004.7 6580.4 6658.3 6748.4 6755.3 6777.6 6755.4 6881.6 6885.2 6903.3 6992.7 6699.2 6738.9 6757.5 6758.0 6802.4 6753.2 6846.0 6847.9 6849.0 6861.4 6882.4 6883.0 6892.4 6975.2 6982.4 6553.8 6643.8 6651.4 6654.2 6655.0 6624.1 6642.6 6643.2 6646.0 6653.3 6501.1 6547.3 6561.5 6574.6 6579.3 6643.5 6692.9 6728.9 6795.8 6847.8 6373.8 6455.5 6477.2 6509.0 6614.6 11:34:37 INFO - PID 1580 | 11:34:37 INFO - PID 1580 | [#1] ext.html Cycles:180 Average:36595.77 Median:34854.91 stddev:54604.45 (156.7%) stddev-sans-first:54691.45 11:34:37 INFO - PID 1580 | Values: 791.2 813.2 838.9 845.7 1052.0 27776.7 29146.0 38275.6 38765.6 38883.2 38871.6 38960.8 39202.8 39277.4 39317.0 7241.6 7709.3 7828.8 7845.3 7873.0 7686.4 7800.7 7810.5 7838.5 7840.3 7789.8 7817.3 7846.1 7854.4 7870.3 7758.4 7790.3 7799.8 7827.6 7837.1 7626.9 7641.2 7646.2 7646.6 7663.5 34785.7 34853.8 34856.0 34966.0 35007.6 3175.7 3204.4 3215.6 3217.5 3221.2 29703.4 29713.4 29843.9 29850.3 29879.6 5180.7 5182.3 5221.9 5227.5 5242.7 180057.0 180649.0 180887.0 181095.1 181375.0 43608.6 43734.1 43845.6 43850.0 43877.6 45735.3 45792.1 45813.3 45847.3 45889.3 14017.4 14319.9 14328.9 14337.2 14351.4 9454.0 9635.9 9649.4 10915.6 11027.4 12635.7 12699.8 12725.5 12765.5 12823.0 5099.5 5116.2 5129.8 5138.1 5171.3 935.2 938.0 938.8 941.7 958.6 21951.7 21957.7 22245.3 22254.7 22853.9 1059.5 1060.5 1063.6 1070.0 1080.4 11697.7 11768.2 11870.3 11881.3 11885.0 241374.0 241475.0 242658.0 242809.0 243019.0 48388.0 48598.0 48609.3 48637.0 48732.8 1294.8 1299.3 1301.4 1304.1 1357.1 21361.7 21363.7 21656.4 21725.3 22180.2 34750.7 34928.7 35000.6 35026.5 35052.1 202889.0 203114.0 203355.0 203839.0 204042.0 32582.2 32818.5 33917.7 35014.6 35119.3 42168.8 42948.0 42975.4 43168.4 43410.3 31346.2 31352.0 31431.8 31442.8 31549.7 31484.4 31562.0 31575.7 31690.4 31923.5 35491.6 36028.3 36253.5 36298.8 36372.0 30989.5 31106.2 31127.8 31173.8 31225.0 20090.2 23221.9 23261.1 23831.0 24077.8 11:34:37 INFO - PID 1580 | 11:34:37 INFO - PID 1580 | [#2] jquery.html Cycles:180 Average:35455.09 Median:22318.94 stddev:88338.46 (395.8%) stddev-sans-first:88547.37 11:34:37 INFO - PID 1580 | Values: 538.6 546.9 547.8 556.3 562.2 20651.9 21178.4 21330.3 21408.0 21408.5 21652.1 22038.6 22138.9 22168.0 22190.8 6394.4 6531.2 6537.7 6541.8 6542.1 5853.9 6444.6 6455.4 6460.7 6464.7 6439.7 6440.1 6448.6 6449.1 6458.6 6410.3 6426.4 6429.9 6432.7 6441.2 5976.8 5980.4 5987.7 6011.7 6015.0 26727.9 27113.3 27544.2 27994.3 28186.2 2022.9 2060.9 2072.3 2074.1 2075.6 18321.2 18974.0 18983.5 18992.6 19020.4 2324.3 2355.3 2363.7 2375.3 2476.4 128825.4 132626.0 132786.3 133055.0 136295.0 13362.4 13871.8 13880.3 14340.6 14484.7 22124.2 22160.9 22208.2 22226.3 22250.4 9671.0 9701.1 9736.3 9939.3 9981.3 7131.6 7450.6 8904.9 8949.5 9009.5 7813.5 8496.8 8499.8 8535.7 8854.9 2530.3 2547.2 2553.6 2621.3 2699.4 904.8 921.1 923.1 928.1 934.5 18241.5 18450.3 18664.3 18891.1 18938.5 959.2 976.5 978.3 980.9 981.2 10190.9 10193.5 10361.2 10499.4 10552.6 491451.0 494452.0 544686.0 546421.9 549839.0 121885.0 122600.0 125635.0 126871.4 126963.4 1123.9 1127.4 1162.9 1166.5 1170.2 19200.7 19396.6 19701.4 20072.8 20520.8 57713.4 58207.7 59156.0 59158.1 59987.4 70960.0 71197.6 71374.0 71670.9 72153.0 12287.7 14339.6 14508.5 14521.9 14553.1 22253.3 22384.6 22417.7 22529.5 22589.8 16362.0 16622.2 16625.4 16646.0 16836.8 15791.7 15911.4 16193.0 16444.0 16698.1 18338.4 18382.3 18613.5 18647.2 18892.5 14248.6 14262.6 14291.6 14311.1 14313.0 17081.2 17352.6 17655.9 17868.4 17967.9 11:34:37 INFO - PID 1580 | 11:34:37 INFO - PID 1580 | [#3] mootools.html Cycles:180 Average:8912.17 Median:13736.43 stddev:6350.79 (46.2%) stddev-sans-first:6342.24 11:34:37 INFO - PID 1580 | Values: 1210.4 1248.9 1265.3 1271.1 1277.2 14029.4 14109.2 14789.9 14798.7 14803.4 14627.7 14970.9 14987.3 15005.3 15025.3 2503.1 2528.2 2534.2 2554.8 2556.3 2286.6 2374.2 2383.9 2674.7 2787.0 2718.1 2721.6 2723.3 2727.9 2728.5 2619.8 2668.7 2671.2 2678.0 2678.1 2409.5 2418.5 2431.4 2433.4 2439.5 14075.4 14188.1 14193.0 14210.4 14242.0 7560.7 7821.4 7821.9 7824.0 7837.8 5045.1 5245.4 5253.3 5368.8 5404.1 1783.8 1818.9 1852.1 1855.7 1862.2 24551.9 24730.8 24897.6 24969.8 25036.4 6472.6 6997.2 7217.1 7233.9 7272.3 15680.3 16426.7 16482.4 16510.7 16531.3 7452.4 7654.8 7665.3 7667.5 7672.4 6811.3 7056.2 7061.0 7101.2 7105.5 4661.5 4793.5 4988.8 5006.2 5070.1 1494.9 1499.1 1505.4 1518.0 1520.4 806.7 811.4 823.9 829.2 833.1 7581.7 7964.4 7972.1 7989.5 8071.8 934.8 942.8 948.3 952.2 971.2 4383.5 4390.8 5269.6 5379.0 5466.8 19758.4 20424.0 20659.9 20858.6 21077.9 20189.4 20257.0 20452.1 20465.8 20546.0 10549.9 10637.5 10845.9 10885.8 10897.4 7745.9 7919.9 8018.8 8280.5 8384.2 18035.5 18104.3 18224.8 18378.3 18403.5 18398.4 18507.2 18701.3 18780.7 18949.4 9062.7 11312.7 11597.2 11615.7 11633.3 10870.1 12267.4 12669.2 12674.4 12700.9 10356.9 10360.1 11675.5 11680.2 11765.3 10430.9 10671.0 10770.2 10789.9 11077.9 8970.8 9133.7 9213.5 9220.9 9227.0 6601.9 6643.6 6660.3 6665.0 6762.0 6588.9 6603.1 6628.9 6655.8 6721.1 11:34:37 INFO - PID 1580 | 11:34:37 INFO - PID 1580 | [#4] prototype.html Cycles:180 Average:5502.84 Median:6091.74 stddev:1554.53 (25.5%) stddev-sans-first:1512.50 11:34:37 INFO - PID 1580 | Values: 480.8 491.8 509.6 514.4 514.9 6277.0 6287.7 6349.3 6353.5 6383.2 5931.5 5934.6 5984.8 6121.7 6235.0 4910.2 4962.7 4966.7 4980.7 4992.6 4822.4 4948.2 4977.4 4997.3 5038.3 4817.6 4911.8 4936.3 4968.6 4996.2 4904.6 4984.9 4997.8 5007.8 5008.1 4424.9 4428.3 4428.6 4434.8 4446.1 6028.5 6068.8 6077.3 6106.2 6181.4 5626.3 5652.5 5671.7 5700.3 5717.7 6452.8 6475.8 6507.2 6533.3 6577.6 4816.4 4833.8 4846.4 4876.1 4896.2 6795.0 6835.5 6893.0 6951.5 6965.6 4935.0 4953.7 4967.4 4994.6 4995.4 5043.2 5048.4 5052.3 5060.3 5064.6 5660.5 5706.7 5712.0 5715.7 5743.6 6444.7 6507.3 6514.9 6526.2 6612.8 1645.5 1666.5 1680.8 1736.4 1738.1 1197.3 1212.7 1224.6 1242.4 1250.4 5647.8 5676.0 5697.2 5736.2 5842.2 6510.8 6591.8 6607.1 6640.6 6690.1 5756.6 5784.5 5830.1 5850.2 5874.3 3512.4 3517.8 3564.9 3568.0 3587.4 6530.2 6536.2 6556.4 6576.2 6580.3 6874.6 6967.2 6988.8 7006.8 7050.0 6492.3 6525.4 6570.7 6604.0 6636.0 6642.0 6646.7 6675.6 6740.2 6768.2 6652.3 6715.3 6743.7 6776.8 6795.5 6557.9 6582.7 6611.7 6614.6 6642.6 4982.7 5006.2 5017.2 5029.9 5036.8 6740.6 6747.2 6751.4 6802.8 6847.6 6573.8 6574.7 6611.2 6622.8 6631.8 6296.5 6508.9 6539.2 6541.6 6587.8 6201.4 6264.4 6361.9 6370.4 6391.6 6437.7 6465.5 6475.4 6488.9 6508.0 6207.5 6240.0 6277.2 6303.0 6317.6 11:34:37 INFO - PID 1580 | 11:34:37 INFO - PID 1580 | [#5] yui.html Cycles:180 Average:22565.64 Median:26179.54 stddev:32210.76 (123.0%) stddev-sans-first:32259.68 11:34:37 INFO - PID 1580 | Values: 807.5 807.7 820.6 827.2 830.4 16211.2 16482.8 16489.3 16573.2 16606.1 15813.0 16427.1 17046.7 17097.4 17150.7 778.7 788.9 789.4 792.6 792.9 725.5 791.3 798.4 802.3 804.5 833.6 835.5 835.6 835.7 838.3 836.7 837.8 838.3 838.8 838.9 609.0 610.2 610.2 610.9 611.0 15308.6 15333.4 15377.5 15394.3 15416.8 18255.6 18665.6 18765.7 18809.2 18878.5 14662.9 15040.6 15187.8 15271.3 15299.9 3735.6 3769.9 3772.5 3772.7 3780.1 118605.4 119624.4 120535.4 123257.0 123906.0 14845.4 14926.5 15036.7 15150.6 15238.5 26147.0 26212.1 26243.5 26927.6 27052.3 8786.4 8839.0 8857.6 8878.7 8885.8 4198.1 4204.5 4227.1 4253.7 4304.0 4348.7 4526.7 4633.1 4722.3 5340.2 949.4 967.1 983.4 992.4 996.1 655.7 667.2 681.2 683.8 692.1 11252.0 11558.5 11966.0 12030.0 12317.1 661.3 672.7 675.9 679.6 687.6 5576.1 5618.5 5643.3 5662.6 5770.9 110373.0 111167.0 112588.4 112589.0 117208.4 103118.0 104622.5 105592.9 108715.5 109281.5 84162.6 86193.1 86668.6 88752.1 89651.6 10535.5 10610.2 10692.0 10743.6 10893.2 41555.8 41927.8 42219.4 42509.0 43390.3 52831.5 53568.7 54400.0 56104.4 56565.4 15002.5 15429.8 15576.5 15597.4 15600.9 17568.5 17891.7 17919.6 18014.3 18036.5 14061.5 16465.0 16518.5 16521.2 16737.9 15619.1 15693.1 15705.4 15776.4 15889.4 16439.8 16471.6 16552.3 16577.1 16669.4 12206.8 12814.7 12947.4 13155.3 13250.5 12429.6 12454.7 12668.6 12885.2 13907.2 11:34:37 INFO - PID 1580 | -------- Summary: end -------- 11:34:37 INFO - PID 1580 | 11:34:38 INFO - TEST-INFO | 1580: exit 0 11:34:38 INFO - TEST-OK | dromaeo_css | took 1111653ms 11:34:38 INFO - TEST-START | kraken 11:34:38 INFO - Initialising browser for kraken test... 11:34:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51983/getInfo.html -profile /tmp/tmpgpazFg/profile 11:34:38 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/firefox/firefox http://localhost:51983/getInfo.html) 11:34:44 INFO - TEST-INFO | 1955: exit 0 11:34:44 INFO - Browser initialized. 11:34:44 INFO - Running cycle 1/1 for kraken test... 11:34:44 INFO - Using env: {'DISPLAY': ':0', 11:34:44 INFO - 'HOME': '/home/cltbld', 11:34:44 INFO - 'JSGC_DISABLE_POISONING': '1', 11:34:44 INFO - 'LANG': 'en_US.UTF-8', 11:34:44 INFO - 'LANGUAGE': 'en_US:en', 11:34:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:34:44 INFO - 'LOGNAME': 'cltbld', 11:34:44 INFO - 'MAIL': '/var/mail/cltbld', 11:34:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:34:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:34:44 INFO - 'MOZ_CRASHREPORTER': '1', 11:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:34:44 INFO - 'MOZ_NO_REMOTE': '1', 11:34:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:34:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:44 INFO - 'NO_EM_RESTART': '1', 11:34:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:44 INFO - 'PWD': '/builds/slave/test', 11:34:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:34:44 INFO - 'RUST_BACKTRACE': 'full', 11:34:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:34:44 INFO - 'SHELL': '/bin/bash', 11:34:44 INFO - 'SHLVL': '1', 11:34:44 INFO - 'STYLO_FORCE_ENABLED': '1', 11:34:44 INFO - 'TERM': 'linux', 11:34:44 INFO - 'TMOUT': '86400', 11:34:44 INFO - 'USER': 'cltbld', 11:34:44 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486', 11:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:44 INFO - '_': '/tools/buildbot/bin/python'} 11:34:44 INFO - TEST-INFO | started process 2138 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgpazFg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:34:45 INFO - PID 2138 | 11:34:45 INFO - PID 2138 | (/builds/slave/test/build/application/firefox/firefox:2188): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:34:45 INFO - PID 2138 | 11:34:55 INFO - PID 2138 | RSS: Main: 169644032 11:34:55 INFO - PID 2138 | 11:34:58 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51983/tests/kraken/driver.html?testName=audio-beat-detection) 11:34:59 INFO - PID 2138 | RSS: Main: 175648768 11:34:59 INFO - PID 2138 | 11:35:02 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51983/tests/kraken/driver.html?testName=audio-dft) 11:35:02 INFO - PID 2138 | RSS: Main: 179752960 11:35:02 INFO - PID 2138 | 11:35:05 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51983/tests/kraken/driver.html?testName=audio-fft) 11:35:06 INFO - PID 2138 | RSS: Main: 172118016 11:35:06 INFO - PID 2138 | 11:35:08 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51983/tests/kraken/driver.html?testName=audio-oscillator) 11:35:09 INFO - PID 2138 | RSS: Main: 172666880 11:35:09 INFO - PID 2138 | 11:35:11 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51983/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:35:11 INFO - PID 2138 | RSS: Main: 172789760 11:35:11 INFO - PID 2138 | 11:35:15 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51983/tests/kraken/driver.html?testName=imaging-darkroom) 11:35:15 INFO - PID 2138 | RSS: Main: 173957120 11:35:15 INFO - PID 2138 | 11:35:19 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51983/tests/kraken/driver.html?testName=imaging-desaturate) 11:35:19 INFO - PID 2138 | RSS: Main: 174145536 11:35:19 INFO - PID 2138 | 11:35:22 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51983/tests/kraken/driver.html?testName=json-parse-financial) 11:35:23 INFO - PID 2138 | RSS: Main: 174018560 11:35:23 INFO - PID 2138 | 11:35:24 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51983/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:35:25 INFO - PID 2138 | RSS: Main: 174120960 11:35:25 INFO - PID 2138 | 11:35:27 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51983/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:35:27 INFO - PID 2138 | RSS: Main: 174194688 11:35:27 INFO - PID 2138 | 11:35:29 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51983/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:35:30 INFO - PID 2138 | RSS: Main: 174264320 11:35:30 INFO - PID 2138 | 11:35:33 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51983/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:35:34 INFO - PID 2138 | RSS: Main: 174702592 11:35:34 INFO - PID 2138 | 11:35:36 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51983/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:35:37 INFO - PID 2138 | RSS: Main: 174678016 11:35:37 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | Cycle 1(1): loaded http://localhost:51983/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51983/tests/kraken/driver.html?testName=ai-astar) 11:35:39 INFO - PID 2138 | RSS: Main: 176046080 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | __start_tp_report 11:35:39 INFO - PID 2138 | _x_x_mozilla_page_load 11:35:39 INFO - PID 2138 | _x_x_mozilla_page_load_details 11:35:39 INFO - PID 2138 | |i|pagename|runs| 11:35:39 INFO - PID 2138 | |0;ai-astar;96;107;99;106;107;107;107;100;109;109 11:35:39 INFO - PID 2138 | |1;audio-beat-detection;142;116;149;166;121;151;143;117;173;119 11:35:39 INFO - PID 2138 | |2;audio-dft;167;168;167;171;168;170;173;172;169;170 11:35:39 INFO - PID 2138 | |3;audio-fft;75;74;73;169;75;74;73;74;149;76 11:35:39 INFO - PID 2138 | |4;audio-oscillator;83;79;132;80;78;79;135;79;79;124 11:35:39 INFO - PID 2138 | |5;imaging-gaussian-blur;115;114;115;114;115;114;114;114;114;114 11:35:39 INFO - PID 2138 | |6;imaging-darkroom;149;147;148;149;148;148;147;149;148;149 11:35:39 INFO - PID 2138 | |7;imaging-desaturate;93;94;94;94;95;94;96;94;95;94 11:35:39 INFO - PID 2138 | |8;json-parse-financial;55;53;52;51;51;51;51;51;51;51 11:35:39 INFO - PID 2138 | |9;json-stringify-tinderbox;38;38;39;39;38;37;37;38;38;38 11:35:39 INFO - PID 2138 | |10;stanford-crypto-aes;79;75;79;75;75;77;74;75;74;76 11:35:39 INFO - PID 2138 | |11;stanford-crypto-ccm;128;117;117;130;127;126;123;125;124;159 11:35:39 INFO - PID 2138 | |12;stanford-crypto-pbkdf2;181;170;171;172;169;184;180;169;168;167 11:35:39 INFO - PID 2138 | |13;stanford-crypto-sha256-iterative;70;63;67;67;63;63;65;63;64;62 11:35:39 INFO - PID 2138 | __end_tp_report 11:35:39 INFO - PID 2138 | __start_cc_report 11:35:39 INFO - PID 2138 | _x_x_mozilla_cycle_collect,928 11:35:39 INFO - PID 2138 | __end_cc_report 11:35:39 INFO - PID 2138 | __startTimestamp1503945339639__endTimestamp 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | ------- Summary: start ------- 11:35:39 INFO - PID 2138 | Number of tests: 14 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#0] ai-astar Cycles:10 Average:104.70 Median:108.00 stddev:4.60 (4.3%) stddev-sans-first:3.64 11:35:39 INFO - PID 2138 | Values: 96.0 107.0 99.0 106.0 107.0 107.0 107.0 100.0 109.0 109.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:146.00 stddev:20.78 (14.2%) stddev-sans-first:22.02 11:35:39 INFO - PID 2138 | Values: 142.0 116.0 149.0 166.0 121.0 151.0 143.0 117.0 173.0 119.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#2] audio-dft Cycles:10 Average:169.50 Median:170.00 stddev:2.07 (1.2%) stddev-sans-first:1.99 11:35:39 INFO - PID 2138 | Values: 167.0 168.0 167.0 171.0 168.0 170.0 173.0 172.0 169.0 170.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#3] audio-fft Cycles:10 Average:91.20 Median:74.00 stddev:36.05 (48.7%) stddev-sans-first:37.76 11:35:39 INFO - PID 2138 | Values: 75.0 74.0 73.0 169.0 75.0 74.0 73.0 74.0 149.0 76.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:24.70 (31.3%) stddev-sans-first:25.83 11:35:39 INFO - PID 2138 | Values: 83.0 79.0 132.0 80.0 78.0 79.0 135.0 79.0 79.0 124.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 11:35:39 INFO - PID 2138 | Values: 115.0 114.0 115.0 114.0 115.0 114.0 114.0 114.0 114.0 114.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 11:35:39 INFO - PID 2138 | Values: 149.0 147.0 148.0 149.0 148.0 148.0 147.0 149.0 148.0 149.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 11:35:39 INFO - PID 2138 | Values: 93.0 94.0 94.0 94.0 95.0 94.0 96.0 94.0 95.0 94.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:51.00 stddev:1.34 (2.6%) stddev-sans-first:0.71 11:35:39 INFO - PID 2138 | Values: 55.0 53.0 52.0 51.0 51.0 51.0 51.0 51.0 51.0 51.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#9] json-stringify-tinderbox Cycles:10 Average:38.00 Median:38.00 stddev:0.67 (1.8%) stddev-sans-first:0.71 11:35:39 INFO - PID 2138 | Values: 38.0 38.0 39.0 39.0 38.0 37.0 37.0 38.0 38.0 38.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#10] stanford-crypto-aes Cycles:10 Average:75.90 Median:75.50 stddev:1.85 (2.5%) stddev-sans-first:1.59 11:35:39 INFO - PID 2138 | Values: 79.0 75.0 79.0 75.0 75.0 77.0 74.0 75.0 74.0 76.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#11] stanford-crypto-ccm Cycles:10 Average:127.60 Median:126.50 stddev:11.83 (9.4%) stddev-sans-first:12.55 11:35:39 INFO - PID 2138 | Values: 128.0 117.0 117.0 130.0 127.0 126.0 123.0 125.0 124.0 159.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:171.50 stddev:6.15 (3.6%) stddev-sans-first:5.83 11:35:39 INFO - PID 2138 | Values: 181.0 170.0 171.0 172.0 169.0 184.0 180.0 169.0 168.0 167.0 11:35:39 INFO - PID 2138 | 11:35:39 INFO - PID 2138 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:64.50 stddev:2.54 (3.9%) stddev-sans-first:1.83 11:35:39 INFO - PID 2138 | Values: 70.0 63.0 67.0 67.0 63.0 63.0 65.0 63.0 64.0 62.0 11:35:39 INFO - PID 2138 | -------- Summary: end -------- 11:35:39 INFO - PID 2138 | 11:35:40 INFO - PID 2138 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:35:40 INFO - TEST-INFO | 2138: exit 0 11:35:40 INFO - TEST-OK | kraken | took 61773ms 11:35:40 INFO - SUITE-END | took 1173s 11:35:40 INFO - Completed test suite (00:19:33) 11:35:40 INFO - javascript benchmark 11:35:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8257.164036440136, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3336.0825772912444, 3359.3617212729578, 3365.242820365417, 3378.763486555942, 3389.4576867701157, 6327.145835312232, 6368.044793280998, 6372.362763723631, 6375.362463753628, 6411.358864113587, 6261.123442718023, 6374.585651932633, 6377.489800815935, 6402.16771819664, 6412.198475190601, 5853.356130825595, 5867.7066146692705, 5878.88242235155, 5886.499647529965, 5899.085641725539, 5883.823485295448, 5903.940960590382, 5904.409559044104, 5907.940920590803, 5910.113482977545, 5933.7033148342625, 5935.465808077272, 5951.58338916276, 5958.225430694004, 5970.731317090741, 5875.059990401558, 5891.322497912752, 5928.02187639039, 5953.047512397996, 6005.069214271773, 5630.0991841305195, 5782.190493330937, 5823.213866128103, 5874.794382196603, 5877.089051197071, 5688.41591580555, 6247.531435142382, 6264.966280563702, 6327.27236367819, 6419.133416988693, 6803.795886123424, 6848.1097457330225, 6863.0, 6943.79168624937, 6968.0, 6856.24581296057, 6908.171019477644, 7011.088558487415, 7012.824679383005, 7018.368346848808, 6443.840108780464, 6458.483321334282, 6462.935370646281, 6468.8059358219325, 6470.805875823733, 6974.163100427981, 7095.82260443493, 7112.075430194145, 7115.324044215791, 7152.9284707152265, 6756.358145976124, 6770.0, 6842.418394436529, 6857.417119544894, 6879.690413931285, 6731.932680673229, 6750.797476075725, 6801.829954251084, 6828.6927088281145, 6956.026156338116, 6816.113905192294, 6907.620080895498, 6911.481638877104, 6912.723491060402, 6918.446524278046, 6782.9321706783285, 6865.313468653095, 6870.244273129952, 6979.860402792017, 6986.825329366705, 5297.920531192035, 5373.408925018245, 5398.541124004425, 5412.242286079952, 5436.075867102602, 4719.787609557578, 4773.284007398918, 4837.685550439211, 4843.903121937541, 4863.683860549053, 5821.388754180834, 5901.645901245853, 5977.491913187341, 5982.700864956735, 6042.395760423922, 6806.251312355637, 6828.078209441761, 6831.863362732717, 6843.623600702009, 6848.075509806212, 5911.852203694942, 5915.142304365843, 5928.288605367384, 5975.103734439869, 6020.69896505173, 5895.67573783446, 5985.521158307412, 5991.3409524953095, 6000.21997140369, 6004.729787159501, 6580.374864387875, 6658.334166583303, 6748.426383757435, 6755.256921738604, 6777.6272305023695, 6755.392014718699, 6881.552699074544, 6885.173779146535, 6903.275156108536, 6992.650367481504, 6699.162604674416, 6738.898916516354, 6757.459403247827, 6758.0, 6802.3537763912345, 6753.223379311442, 6846.041554182518, 6847.897281540681, 6849.0, 6861.416779573592, 6882.449404047764, 6883.0, 6892.379685828299, 6975.232724400414, 6982.406495447741, 6553.770618028346, 6643.800685979429, 6651.401373876374, 6654.168228971379, 6655.001749737578, 6624.105745724266, 6642.634655094016, 6643.169603799525, 6645.966770166118, 6653.301403352578, 6501.057346684799, 6547.345265473605, 6561.475081993524, 6574.572652777554, 6579.276279609335, 6643.534952553275, 6692.866142677021, 6728.932710672831, 6795.7961261162245, 6847.794566163023, 6373.808785736435, 6455.515836312389, 6477.222733272038, 6508.967455162694, 6614.603123812587], "value": 6268.212774537774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.1969351108626, 813.2071230550214, 838.9328853691705, 845.7166849105549, 1051.9947400262997, 27776.722232777665, 29146.0, 38275.61724382759, 38765.61234387655, 38883.22233555327, 38871.611283887156, 38960.80519597398, 39202.8039859801, 39277.41083883744, 39317.0, 7241.6379181041, 7709.3447057000085, 7828.843423131534, 7845.293923546879, 7873.015873015873, 7686.423518236126, 7800.7269745558915, 7810.49231799934, 7838.529688218697, 7840.333571646417, 7789.805254868617, 7817.2964433201005, 7846.136924938252, 7854.3716502679645, 7870.2916737493615, 7758.418118641096, 7790.337821285169, 7799.844003119934, 7827.608619569056, 7837.059552853637, 7626.923730762677, 7641.23587641237, 7646.235376462365, 7646.623607750658, 7663.501872378278, 34785.65214347875, 34853.82573087118, 34856.0, 34966.0, 35007.64992350095, 3175.6983086606733, 3204.4071846708434, 3215.581974343344, 3217.48520236761, 3221.2269055426764, 29703.40593188124, 29713.405731885457, 29843.850780746176, 29850.253743656365, 29879.551806722917, 5180.663256888291, 5182.300389447414, 5221.921671174898, 5227.47725227478, 5242.711650859202, 180057.0, 180649.0, 180887.0, 181095.09452452784, 181375.0, 43608.56391436108, 43734.12531749411, 43845.561544384786, 43850.0, 43877.56122438799, 45735.313970290474, 45792.08415831665, 45813.31280030802, 45847.31229031567, 45889.31166032512, 14017.439302427993, 14319.928400357932, 14328.928355358365, 14337.211453370164, 14351.425942962373, 9454.0, 9635.903640963501, 9649.372790768577, 10915.563377464812, 11027.393493357782, 12635.684107897376, 12699.809502857464, 12725.490980360866, 12765.489380424679, 12823.0, 5099.515546023122, 5116.181410974226, 5129.820456284012, 5138.100832354397, 5171.276021357013, 935.2237642756631, 937.9718608441758, 938.8310104181177, 941.6704153546204, 958.6021800952666, 21951.67072493914, 21957.67063494049, 22245.332640020824, 22254.666180007313, 22853.88573057124, 1059.5073290919759, 1060.4591658254346, 1063.622414043019, 1069.9625513107003, 1080.4165750494644, 11697.707557311136, 11768.235064720766, 11870.28778273289, 11881.346525940984, 11885.0, 241374.0, 241475.0, 242658.0, 242809.0, 243019.0, 48388.0, 48598.0, 48609.27086093711, 48637.0, 48732.756336218095, 1294.779887419141, 1299.2529295655036, 1301.4013553765374, 1304.067591671978, 1357.1314358810369, 21361.67957480608, 21363.67954480715, 21656.37577181686, 21725.348239552837, 22180.223692170697, 34750.652493475754, 34928.65071349254, 35000.64999349974, 35026.474602881484, 35052.12369690676, 202889.0, 203114.0, 203355.0, 203839.0, 204042.0, 32582.185445363106, 32818.50772238371, 33917.66082339145, 35014.64985350216, 35119.29761404809, 42168.78915605402, 42948.0, 42975.355369670106, 43168.35247471353, 43410.34884476672, 31346.21634459157, 31352.0, 31431.842840785648, 31442.842785785924, 31549.684503154676, 31484.370312594077, 31562.0, 31575.684243157273, 31690.366192675556, 31923.521147183274, 35491.645083548836, 36028.27943441169, 36253.45619815652, 36298.8185059073, 36372.0, 30989.535156973114, 31106.22234444157, 31127.84436077805, 31173.8441307792, 31225.0, 20090.19639214415, 23221.883890581114, 23261.06955721752, 23831.04675812948, 24077.75922240825], "value": 16325.842065901938, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.5637633516855, 546.8578169675885, 547.8082671065125, 556.2518412734872, 562.1904457581082, 20651.896740516295, 21178.364649060553, 21330.253441129564, 21408.036638351274, 21408.46478838026, 21652.133914643433, 22038.559228815415, 22138.88930555345, 22168.002439890202, 22190.778092219072, 6394.392532709385, 6531.24890637578, 6537.673116344187, 6541.836454088639, 6542.084108224853, 5853.8829223415505, 6444.581102228363, 6455.35446455353, 6460.709268082947, 6464.74141034358, 6439.678016099199, 6440.13058237139, 6448.64532450715, 6449.129367535393, 6458.580192287486, 6410.326915673833, 6426.389492998158, 6429.935700642981, 6432.742690292406, 6441.162648855666, 5976.820695379146, 5980.43185897339, 5987.700614969278, 6011.669358185298, 6014.96992515039, 26727.866360668268, 27113.322166945785, 27544.17367478979, 27994.3001424964, 28186.15441536757, 2022.8988550572412, 2060.8866512341815, 2072.347210628647, 2074.1081335025933, 2075.5952589245103, 18321.17554710028, 18974.0, 18983.525411864677, 18992.62014759711, 19020.42938711827, 2324.2910912171783, 2355.328731311568, 2363.7281712602926, 2375.263668262844, 2476.36852602587, 128825.35587322098, 132626.0, 132786.33606832, 133055.0, 136295.0, 13362.398692058687, 13871.791923121162, 13880.305984700724, 14340.637639424238, 14484.710305793826, 22124.2256521021, 22160.889195554242, 22208.22271220532, 22226.333210003726, 22250.443738906655, 9671.032896710272, 9701.126898579021, 9736.269779766546, 9939.304248702667, 9981.251406144424, 7131.572105673677, 7450.590217538087, 8904.8664270036, 8949.507777072193, 9009.459432434074, 7813.531188128617, 8496.830063398822, 8499.787505312417, 8535.695362252281, 8854.86717699235, 2530.291518374858, 2547.1721690450677, 2553.6041913503436, 2621.252942911261, 2699.3656490724666, 904.7738065483629, 921.1479381572153, 923.086144716739, 928.053385546752, 934.5047125023748, 18241.543961401072, 18450.26198952027, 18664.34674786403, 18891.14989825461, 18938.52653683669, 959.194276807485, 976.5312649928075, 978.2858513285341, 980.9068138526828, 981.2297346582898, 10190.898091019144, 10193.541290641939, 10361.222908281909, 10499.370037797757, 10552.630657926935, 491451.0, 494452.0, 544686.0, 546421.8752343649, 549839.0, 121885.0, 122599.99999999821, 125635.0, 126871.36564317119, 126963.36518317534, 1123.8763735989035, 1127.3686735428187, 1162.918595698293, 1166.504235699841, 1170.2451918512681, 19200.71198932045, 19396.612067758848, 19701.40895773129, 20072.799272007094, 20520.79479205189, 57713.42286577081, 58207.70896145492, 59156.0, 59158.11262831147, 59987.40012599818, 70960.0, 71197.64401178168, 71374.0, 71670.92493612705, 72153.0, 12287.69280767988, 14339.641508961942, 14508.492202772852, 14521.85478145205, 14553.054051486619, 22253.331000174727, 22384.552308953404, 22417.66373504366, 22529.549409012056, 22589.774102259424, 16362.0, 16622.168891555615, 16625.418110366078, 16646.0, 16836.831631683526, 15791.68416631638, 15911.363545458053, 16193.0, 16444.0, 16698.081605511812, 18338.358157464423, 18382.264709411473, 18613.534661633024, 18647.160877760263, 18892.527686807938, 14248.572542823731, 14262.57212283633, 14291.57125286243, 14311.141331520143, 14313.0, 17081.23134458977, 17352.56618584495, 17655.911720441316, 17868.374606888698, 17967.910160449115], "value": 11605.381338338948, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1210.406900618697, 1248.9000879929606, 1265.2598230035428, 1271.1165739810835, 1277.195366918841, 14029.36867840947, 14109.153450792946, 14789.926050369746, 14798.704025919475, 14803.40786368544, 14627.707445851076, 14970.850291497081, 14987.250637468138, 15005.324760385782, 15025.32386042628, 2503.1113954546195, 2528.190978886757, 2534.2270607464716, 2554.8083893708044, 2556.322574517754, 2286.5769832581027, 2374.2402431222017, 2383.904643814254, 2674.7191544887796, 2787.038471727261, 2718.130198336533, 2721.5781553859183, 2723.305557082951, 2727.890884364623, 2728.5497892847757, 2619.76422122009, 2668.7331266873352, 2671.19864040788, 2677.973220267792, 2678.1429942418436, 2409.506051259488, 2418.480026794248, 2431.4286142756437, 2433.4281443860677, 2439.499902519979, 14075.436982520687, 14188.148711077369, 14193.0, 14210.431582736781, 14242.0, 7560.73537426193, 7821.413393995473, 7821.921780782176, 7824.0, 7837.843243135162, 5045.117104506733, 5245.370555533361, 5253.264542963988, 5368.785248590052, 5404.1083221268445, 1783.7502749615064, 1818.863585231113, 1852.148011914521, 1855.7309190167484, 1862.245790454868, 24551.87724061386, 24730.752692473026, 24897.62653560198, 24969.750302496923, 25036.374090647696, 6472.644004579699, 6997.160340759141, 7217.097862767154, 7233.927660723325, 7272.309130632581, 15680.294386752623, 16426.671466570842, 16482.42311519115, 16510.6697866042, 16531.256093475822, 7452.366548843409, 7654.808629784188, 7665.271799179069, 7667.463277570517, 7672.386209103258, 6811.318868113149, 7056.223815380304, 7061.0, 7101.183363913113, 7105.467089968273, 4661.487236403993, 4793.496682848251, 4988.775505102279, 5006.249062640633, 5070.138076527, 1494.902831315961, 1499.130504307499, 1505.4354617018619, 1517.9772303415457, 1520.376645575331, 806.7136166660869, 811.350919264586, 823.8805373220849, 829.2495291760966, 833.0877688930611, 7581.734639287599, 7964.442489025712, 7972.123066462685, 7989.480683755537, 8071.83856322882, 934.8130373925106, 942.8114377124602, 948.3029972969862, 952.2286947572488, 971.2327261463365, 4383.473983122046, 4390.758508282075, 5269.55208807248, 5379.0, 5466.808661696901, 19758.40724778259, 20424.0, 20659.8967005164, 20858.582828343348, 21077.894610527157, 20189.394318170478, 20256.98715064241, 20452.079656415495, 20465.795342046687, 20546.0, 10549.947250263853, 10637.521311540999, 10845.859121721216, 10885.782284354269, 10897.400642964554, 7745.883811742716, 7919.920800791919, 8018.799530011563, 8280.533970910636, 8384.245417912418, 18035.549111271797, 18104.275828966693, 18224.817751822313, 18378.264869405073, 18403.53991150232, 18398.356057537923, 18507.167177477317, 18701.34545290908, 18780.718289225944, 18949.43151705451, 9062.682806101755, 11312.660620181408, 11597.246178998337, 11615.6515304541, 11633.302001879914, 10870.130389568658, 12267.447964841425, 12669.239845609292, 12674.429650665557, 12700.87299126997, 10356.896431035593, 10360.067593916283, 11675.532978680758, 11680.240784348991, 11765.294082355085, 10430.947845260725, 10671.0, 10770.192235582519, 10789.892101078889, 11077.889221107685, 8970.775730606787, 9133.725988220363, 9213.493257870881, 9220.907790922005, 9226.9538652309, 6601.867962640817, 6643.60138391698, 6660.267370589135, 6664.966675166593, 6761.966190169214, 6588.901166482411, 6603.141591593062, 6628.867422651617, 6655.767048153291, 6721.059051732859], "value": 6375.0629056364305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.77403620298446, 491.76677863718635, 509.56177687189034, 514.4135685318737, 514.8995945790572, 6276.968615156923, 6287.685615719213, 6349.333320001402, 6353.523485738565, 6383.170187875569, 5931.5254779617635, 5934.554908381867, 5984.790532331369, 6121.724522396492, 6235.0, 4910.238912968487, 4962.6774259673, 4966.726830024347, 4980.676256043345, 4992.575631071363, 4822.445418776833, 4948.204264590513, 4977.402711674604, 4997.275395067713, 5038.345015148026, 4817.639356715219, 4911.778969946361, 4936.259561065851, 4968.577670897978, 4996.150654388744, 4904.558589726942, 4984.90030199394, 4997.750112494397, 5007.84976450707, 5008.12357837377, 4424.867253982386, 4428.291473364278, 4428.557144285578, 4434.800433980478, 4446.110777844444, 6028.5478589106, 6068.787592434288, 6077.298356460198, 6106.175666285084, 6181.350958149419, 5626.324841019063, 5652.5195358394585, 5671.716414179275, 5700.2589663343915, 5717.68433784318, 6452.774152904626, 6475.805725828232, 6507.2191337039385, 6533.314002029766, 6577.572457790228, 4816.373871396731, 4833.782479788383, 4846.39420072491, 4876.097921884463, 4896.216605343164, 6794.9660251698915, 6835.453163746888, 6893.0, 6951.478639102037, 6965.58686243742, 4935.0, 4953.653244272867, 4967.3855996801185, 4994.600431965434, 4995.375578052744, 5043.218301163325, 5048.419431765321, 5052.292679024939, 5060.291559181717, 5064.645474816727, 5660.547156227574, 5706.71466426677, 5712.0, 5715.685637289906, 5743.587693168879, 6444.709988050549, 6507.34926507355, 6514.934850651528, 6526.24948130971, 6612.8346791330605, 1645.5392490102552, 1666.5333706562183, 1680.7983042035034, 1736.4096207289583, 1738.1135620833466, 1197.26368283505, 1212.660455072581, 1224.6020043485726, 1242.428482897877, 1250.412306216077, 5647.830565083054, 5675.985025316036, 5697.173909783059, 5736.196932429463, 5842.152887831241, 6510.837229069217, 6591.802245932629, 6607.141071660653, 6640.568363056386, 6690.130283063268, 5756.625819321815, 5784.4504772046585, 5830.125481177857, 5850.180974663465, 5874.265716785402, 3512.4028915084496, 3517.806520641338, 3564.8574057037945, 3567.9643203568153, 3587.3542762302513, 6530.249021362509, 6536.24833144185, 6556.377144171392, 6576.210854697467, 6580.309067547934, 6874.587524748531, 6967.198772141269, 6988.79033628992, 7006.824829379307, 7049.9647501762165, 6492.318306577741, 6525.4453371463005, 6570.747479997432, 6603.966980165068, 6635.966820165965, 6641.966790166019, 6646.667666616747, 6675.598124393931, 6740.191177058588, 6768.1878174617395, 6652.30581614447, 6715.26132125456, 6743.7302507901095, 6776.830579235559, 6795.456363491007, 6557.868842623026, 6582.736690532517, 6611.702473388806, 6614.570052946543, 6642.568233064835, 4982.707954341044, 5006.1739812930455, 5017.222330538772, 5029.949700503094, 5036.84889453317, 6740.561863478858, 6747.224069232101, 6751.358620931003, 6802.761903333359, 6847.554908930903, 6573.820608627874, 6574.704138313692, 6611.239707433707, 6622.801315960336, 6631.767888123893, 6296.496280297473, 6508.902366464412, 6539.215294164731, 6541.603177378586, 6587.769428069994, 6201.441870231701, 6264.371263471439, 6361.904571431525, 6370.426661600478, 6391.58454700443, 6437.710303036468, 6465.482761379172, 6475.417212450902, 6488.870222595616, 6508.012628381995, 6207.503399728102, 6239.96880015597, 6277.246730392382, 6302.968485157545, 6317.5893566918], "value": 5041.617209328883, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [807.4832107451232, 807.6850028488886, 820.5815034332488, 827.2058823529411, 830.410408609887, 16211.189440527985, 16482.752758708615, 16489.257983390748, 16573.17134143291, 16606.086665233423, 15813.0, 16427.09650969196, 17046.74429883553, 17097.40159094432, 17150.656986860256, 778.6963084397075, 788.9329407000383, 789.3842802613971, 792.6353877216459, 792.9286364227219, 725.5392825555783, 791.3471386106456, 798.4291231769283, 802.2659266770897, 804.5454318310136, 833.5540485840077, 835.5362773660646, 835.578033093287, 835.7409203147014, 838.3042075077701, 836.7238811192323, 837.815680550278, 838.337713206566, 838.7693384319361, 838.8993320801512, 609.0, 610.1610285856947, 610.2219669920842, 610.9236345456818, 610.9938900611025, 15308.617284567863, 15333.386664533406, 15377.538673839803, 15394.307256173499, 15416.845831541654, 18255.634887302178, 18665.626687466174, 18765.7185142223, 18809.24763009478, 18878.528036799053, 14662.926685366612, 15040.623984400368, 15187.848121518753, 15271.312790924436, 15299.923500382538, 3735.62643735627, 3769.924601507982, 3772.4718539404507, 3772.6793222576116, 3780.0549862534367, 118605.40697296459, 119624.40187799092, 120535.39732301282, 123257.0, 123906.0, 14845.406183752744, 14926.477573284883, 15036.699266014619, 15150.621234469007, 15238.542843714706, 26147.0, 26212.082577109708, 26243.47513049728, 26927.596086058726, 27052.323691907466, 8786.384953053353, 8838.955805221061, 8857.601407936658, 8878.689245876363, 8885.777855553533, 4198.1393814268, 4204.495460544754, 4227.070044590185, 4253.723507971972, 4303.956960430418, 4348.652107831429, 4526.660500462479, 4633.0733853229485, 4722.291656251521, 5340.172273297645, 949.4350861237552, 967.1150896929372, 983.4050399508337, 992.369845148336, 996.1234113979651, 655.7377049180366, 667.219353356574, 681.1757773094512, 683.774354463026, 692.1348314606741, 11252.0, 11558.504967167908, 11966.042716582653, 12030.015047517061, 12317.07621928359, 661.3485716569176, 672.6703915081574, 675.8513127112027, 679.6228093408206, 687.6149356360454, 5576.1356989666665, 5618.522425593788, 5643.266375371176, 5662.631928924607, 5770.855728606818, 110373.0, 111167.0, 112588.43705781583, 112589.0, 117208.41395792966, 103118.0, 104622.47688761659, 105592.89462417008, 108715.45642271738, 109281.45359273312, 84162.57918710368, 86193.13806861851, 86668.56665716632, 88752.11247887567, 89651.55174224087, 10535.473226338805, 10610.204234682275, 10692.037716605231, 10743.623973160902, 10893.183011274345, 41555.792221038704, 41927.790361048, 42219.36670949877, 42509.0, 43390.34914476222, 52831.47168528265, 53568.73215633897, 54400.0, 56104.43895560992, 56565.43434565602, 15002.549923502313, 15429.845701542841, 15576.532704018897, 15597.376104956129, 15600.92199538995, 17568.472945811645, 17891.73162402539, 17919.552011199303, 18014.2794288227, 18036.54908627295, 14061.507847225706, 16465.0, 16518.504444866674, 16521.173941302648, 16737.91631041837, 15619.14094724801, 15693.058416495047, 15705.371785128924, 15776.447824326553, 15889.443869464976, 16439.753403699193, 16471.588210294838, 16552.255148518103, 16577.088260145807, 16669.416570419977, 12206.755864882474, 12814.743705126031, 12947.417366218731, 13155.342232888508, 13250.46998120103, 12429.627111186679, 12454.688632784253, 12668.619941401772, 12885.226886386847, 13907.165570065828], "value": 8302.867285894848, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1487.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 107.0, 99.0, 106.0, 107.0, 107.0, 107.0, 100.0, 109.0, 109.0], "value": 104.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 149.0, 166.0, 121.0, 151.0, 143.0, 117.0, 173.0, 119.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 167.0, 171.0, 168.0, 170.0, 173.0, 172.0, 169.0, 170.0], "value": 169.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 73.0, 169.0, 75.0, 74.0, 73.0, 74.0, 149.0, 76.0], "value": 91.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 132.0, 80.0, 78.0, 79.0, 135.0, 79.0, 79.0, 124.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 115.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 148.0, 149.0, 148.0, 148.0, 147.0, 149.0, 148.0, 149.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 95.0, 94.0, 96.0, 94.0, 95.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 51.0, 51.0, 51.0, 51.0, 51.0, 51.0, 51.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 39.0, 38.0, 37.0, 37.0, 38.0, 38.0, 38.0], "value": 38.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 79.0, 75.0, 75.0, 77.0, 74.0, 75.0, 74.0, 76.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 117.0, 117.0, 130.0, 127.0, 126.0, 123.0, 125.0, 124.0, 159.0], "value": 127.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 170.0, 171.0, 172.0, 169.0, 184.0, 180.0, 169.0, 168.0, 167.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 63.0, 67.0, 67.0, 63.0, 63.0, 65.0, 63.0, 64.0, 62.0], "value": 64.7, "unit": "score"}]}]} 11:35:40 INFO - Return code: 0 11:35:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:35:40 INFO - # TBPL SUCCESS # 11:35:40 INFO - Running post-action listener: _package_coverage_data 11:35:40 INFO - Running post-action listener: _resource_record_post_action 11:35:40 INFO - [mozharness: 2017-08-28 18:35:40.918650Z] Finished run-tests step (success) 11:35:40 INFO - Running post-run listener: _resource_record_post_run 11:35:40 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 53248; Write bytes: 429928448; Read time: 252; Write time: 229044 11:35:40 INFO - TinderboxPrint: CPU usage
13.1% 11:35:40 INFO - TinderboxPrint: I/O read bytes / time
53,248 / 252 11:35:40 INFO - TinderboxPrint: I/O write bytes / time
429,928,448 / 229,044 11:35:40 INFO - TinderboxPrint: CPU idle
8,260.3 (86.9%) 11:35:40 INFO - TinderboxPrint: CPU user
1,195.7 (12.6%) 11:35:40 INFO - TinderboxPrint: Swap in / out
0 / 0 11:35:40 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 104837120; Read time: 32; Write time: 88300 11:35:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:35:41 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 45056; Write bytes: 325087232; Read time: 208; Write time: 140688 11:35:41 INFO - Running post-run listener: _upload_blobber_files 11:35:41 INFO - Blob upload gear active. 11:35:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:35:41 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 11:35:41 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:35:41 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:35:41 INFO - (blobuploader) - INFO - Open directory for files ... 11:35:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:35:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:35:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:43 INFO - (blobuploader) - INFO - Done attempting. 11:35:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:35:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:35:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:44 INFO - (blobuploader) - INFO - Done attempting. 11:35:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:35:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:35:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:52 INFO - (blobuploader) - INFO - Done attempting. 11:35:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:35:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:35:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:53 INFO - (blobuploader) - INFO - Done attempting. 11:35:53 INFO - (blobuploader) - INFO - Iteration through files over. 11:35:53 INFO - Return code: 0 11:35:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:35:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:35:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c41a36844e9ca963b3df3afed32545525f1fc2c55ede9d22b5528fd7052a993af5c83717148c5668238392fa6261eb5a699eaa6f4af73b7632c87786df534342", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f49ac8198e1c94af13900e326c7189d90d9683da6aafd12104518da052a7b6a6b889b0d6a737329bba98fad201b38fb21a3db7a7d06da98c0177f6af112a9e2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4edca08b3b7a2fd5d507f86d0eb02f1ac6ee0a06a24e719d31056dcf481aed7f0702817787139abe1d1864afe9d7d884ee767d07b7a543a931dd18aabf340845", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aaec319a5bcd4c7404cac27492d5c1ab23de87979ab8bd44c1b69d735f81361e288c8ffeb551773ad0bbd48c2095ebda9cf09860c74f48973f891ba7bd4aad79"} 11:35:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:35:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:35:53 INFO - Contents: 11:35:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c41a36844e9ca963b3df3afed32545525f1fc2c55ede9d22b5528fd7052a993af5c83717148c5668238392fa6261eb5a699eaa6f4af73b7632c87786df534342", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f49ac8198e1c94af13900e326c7189d90d9683da6aafd12104518da052a7b6a6b889b0d6a737329bba98fad201b38fb21a3db7a7d06da98c0177f6af112a9e2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4edca08b3b7a2fd5d507f86d0eb02f1ac6ee0a06a24e719d31056dcf481aed7f0702817787139abe1d1864afe9d7d884ee767d07b7a543a931dd18aabf340845", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aaec319a5bcd4c7404cac27492d5c1ab23de87979ab8bd44c1b69d735f81361e288c8ffeb551773ad0bbd48c2095ebda9cf09860c74f48973f891ba7bd4aad79"} 11:35:53 INFO - Running post-run listener: copy_logs_to_upload_dir 11:35:53 INFO - Copying logs to upload dir... 11:35:53 INFO - mkdir: /builds/slave/test/build/upload/logs 11:35:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.939207 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-28 11:35:53.988532) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 11:35:53.991636) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c41a36844e9ca963b3df3afed32545525f1fc2c55ede9d22b5528fd7052a993af5c83717148c5668238392fa6261eb5a699eaa6f4af73b7632c87786df534342", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f49ac8198e1c94af13900e326c7189d90d9683da6aafd12104518da052a7b6a6b889b0d6a737329bba98fad201b38fb21a3db7a7d06da98c0177f6af112a9e2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4edca08b3b7a2fd5d507f86d0eb02f1ac6ee0a06a24e719d31056dcf481aed7f0702817787139abe1d1864afe9d7d884ee767d07b7a543a931dd18aabf340845", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aaec319a5bcd4c7404cac27492d5c1ab23de87979ab8bd44c1b69d735f81361e288c8ffeb551773ad0bbd48c2095ebda9cf09860c74f48973f891ba7bd4aad79"} build_url:https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011280 build_url: 'https://queue.taskcluster.net/v1/task/RmbeUV6sREG6fr33Z_wCNQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c41a36844e9ca963b3df3afed32545525f1fc2c55ede9d22b5528fd7052a993af5c83717148c5668238392fa6261eb5a699eaa6f4af73b7632c87786df534342", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f49ac8198e1c94af13900e326c7189d90d9683da6aafd12104518da052a7b6a6b889b0d6a737329bba98fad201b38fb21a3db7a7d06da98c0177f6af112a9e2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4edca08b3b7a2fd5d507f86d0eb02f1ac6ee0a06a24e719d31056dcf481aed7f0702817787139abe1d1864afe9d7d884ee767d07b7a543a931dd18aabf340845", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aaec319a5bcd4c7404cac27492d5c1ab23de87979ab8bd44c1b69d735f81361e288c8ffeb551773ad0bbd48c2095ebda9cf09860c74f48973f891ba7bd4aad79"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 11:35:54.017962) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 11:35:54.018288) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ca619d3d7d62c99d832f7b8a000001c4-1503944096.355874-1662749486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 11:35:54.103128) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 11:35:54.103450) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 11:35:54.103737) ========= ========= Total master_lag: 0.20 =========