builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-020 starttime: 1503934324.98 results: success (0) revision: d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:04.982687) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:04.983150) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:04.983443) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:05.012439) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:05.012851) ========= 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=af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-28 08:32:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.4M=0s 2017-08-28 08:32:05 (43.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.212859 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:05.263099) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:05.266434) ========= 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=af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019294 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:05.316005) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-28 08:32:05.319357) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 --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 d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 --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=af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-28 08:32:05,397 truncating revision to first 12 chars 2017-08-28 08:32:05,397 Setting DEBUG logging. 2017-08-28 08:32:05,397 attempt 1/10 2017-08-28 08:32:05,397 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d5b6d113cf17?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-28 08:32:06,511 attempt 1/10 2017-08-28 08:32:07,064 current task status: no status available at this point. state: STARTED 2017-08-28 08:32:07,065 sleeping for 10.00s (attempt 1/10) 2017-08-28 08:32:17,075 attempt 2/10 2017-08-28 08:32:17,411 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-28 08:32:17,652 unpacking tar archive at: mozilla-central-d5b6d113cf17/testing/mozharness/ program finished with exit code 0 elapsedTime=12.630053 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-28 08:32:17.967330) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:17.967870) ========= script_repo_revision: d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:17.968495) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:17.968923) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 08:32:17.982577) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-08-28 08:32:17.982978) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:32:18 INFO - MultiFileLogger online at 20170828 08:32:18 in /builds/slave/test 08:32:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:32:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:32:18 INFO - {'append_to_log': False, 08:32:18 INFO - 'base_work_dir': '/builds/slave/test', 08:32:18 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:32:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:32:18 INFO - 'branch': 'Firefox-Non-PGO', 08:32:18 INFO - 'buildbot_json_path': 'buildprops.json', 08:32:18 INFO - 'code_coverage': False, 08:32:18 INFO - 'config_files': ('talos/linux_config.py',), 08:32:18 INFO - 'default_actions': ('clobber', 08:32:18 INFO - 'read-buildbot-config', 08:32:18 INFO - 'download-and-extract', 08:32:18 INFO - 'populate-webroot', 08:32:18 INFO - 'create-virtualenv', 08:32:18 INFO - 'install', 08:32:18 INFO - 'setup-mitmproxy', 08:32:18 INFO - 'run-tests'), 08:32:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:32:18 INFO - 'disable_ccov_upload': False, 08:32:18 INFO - 'download_minidump_stackwalk': True, 08:32:18 INFO - 'download_symbols': 'ondemand', 08:32:18 INFO - 'enable_stylo': False, 08:32:18 INFO - 'enable_webrender': False, 08:32:18 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:32:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:32:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:32:18 INFO - 'gecko_profile': False, 08:32:18 INFO - 'gecko_profile_interval': 0, 08:32:18 INFO - 'installer_path': 'installer.exe', 08:32:18 INFO - 'log_level': 'info', 08:32:18 INFO - 'log_name': 'talos', 08:32:18 INFO - 'log_to_console': True, 08:32:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:32:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:32:18 INFO - 'opt_config_files': (), 08:32:18 INFO - 'pip_index': False, 08:32:18 INFO - 'suite': 'dromaeojs-e10s', 08:32:18 INFO - 'system_bits': '32', 08:32:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:32:18 INFO - 'title': 'talos-linux64-ix-020', 08:32:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:32:18 INFO - 'use_talos_json': True, 08:32:18 INFO - 'verify': 'False', 08:32:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:32:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:32:18 INFO - 'work_dir': 'build'} 08:32:18 INFO - [mozharness: 2017-08-28 15:32:18.303050Z] Running clobber step. 08:32:18 INFO - Running pre-action listener: _resource_record_pre_action 08:32:18 INFO - Running main action method: clobber 08:32:18 INFO - rmtree: /builds/slave/test/build 08:32:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:32:19 INFO - Running post-action listener: _resource_record_post_action 08:32:19 INFO - [mozharness: 2017-08-28 15:32:19.336089Z] Finished clobber step (success) 08:32:19 INFO - [mozharness: 2017-08-28 15:32:19.336196Z] Running read-buildbot-config step. 08:32:19 INFO - Running pre-action listener: _resource_record_pre_action 08:32:19 INFO - Running main action method: read_buildbot_config 08:32:19 INFO - Using buildbot properties: 08:32:19 INFO - { 08:32:19 INFO - "project": "", 08:32:19 INFO - "product": "firefox", 08:32:19 INFO - "who": "archaeopteryx@coole-files.de", 08:32:19 INFO - "installer_path": "public/build/target.tar.bz2", 08:32:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:32:19 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 08:32:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:32:19 INFO - "stage_platform": "linux64", 08:32:19 INFO - "basedir": "/builds/slave/test", 08:32:19 INFO - "buildnumber": 794, 08:32:19 INFO - "platform": "ubuntu64_hw", 08:32:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:32:19 INFO - "slavebuilddir": "test", 08:32:19 INFO - "taskId": "dh_xUjVNRvyZtZjH83d_6A", 08:32:19 INFO - "branch": "mozilla-central", 08:32:19 INFO - "script_repo_revision": "production", 08:32:19 INFO - "revision": "d5b6d113cf17f4c91b574eaa2d077a233bc4bc69", 08:32:19 INFO - "slavename": "talos-linux64-ix-020", 08:32:19 INFO - "repo_path": "mozilla-central" 08:32:19 INFO - } 08:32:19 INFO - Finding installer, test and symbols from parent task. 08:32:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dh_xUjVNRvyZtZjH83d_6A'}, attempt #1 08:32:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dh_xUjVNRvyZtZjH83d_6A'}, attempt #1 08:32:20 INFO - Task dependencies: Sv4qr0ycSAaRWiVssPK9WA 08:32:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA'}, attempt #1 08:32:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 08:32:20 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.test_packages.json 08:32:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.crashreporter-symbols.zip 08:32:20 INFO - Running post-action listener: _resource_record_post_action 08:32:20 INFO - [mozharness: 2017-08-28 15:32:20.710485Z] Finished read-buildbot-config step (success) 08:32:20 INFO - [mozharness: 2017-08-28 15:32:20.710704Z] Running download-and-extract step. 08:32:20 INFO - Running pre-action listener: _resource_record_pre_action 08:32:20 INFO - Running main action method: download_and_extract 08:32:20 INFO - mkdir: /builds/slave/test/build/tests 08:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:20 INFO - trying https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.test_packages.json 08:32:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:32:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:32:21 INFO - Downloaded 989 bytes. 08:32:21 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:32:21 INFO - Using the following test package requirements: 08:32:21 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:32:21 INFO - u'common': [u'target.common.tests.zip'], 08:32:21 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:32:21 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:32:21 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:32:21 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:32:21 INFO - u'mozbase': [u'target.common.tests.zip'], 08:32:21 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:32:21 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:32:21 INFO - u'web-platform': [u'target.common.tests.zip', 08:32:21 INFO - u'target.web-platform.tests.tar.gz'], 08:32:21 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:32:21 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:32:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip 08:32:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:32:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip into memory 08:32:22 INFO - Content-Length response header: 54977971 08:32:22 INFO - Bytes received: 54977971 08:32:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:32:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip 08:32:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:32:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip into memory 08:32:35 INFO - Content-Length response header: 54977971 08:32:35 INFO - Bytes received: 54977971 08:32:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.talos.tests.zip 08:32:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:32:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.talos.tests.zip into memory 08:32:55 INFO - Content-Length response header: 14496611 08:32:55 INFO - Bytes received: 14496611 08:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:56 INFO - trying https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 08:32:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:32:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:32:57 INFO - Downloaded 61081399 bytes. 08:32:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 08:32:57 INFO - mkdir: /builds/slave/test/properties 08:32:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:32:57 INFO - Writing to file /builds/slave/test/properties/build_url 08:32:57 INFO - Contents: 08:32:57 INFO - build_url:https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 08:32:57 INFO - Running post-action listener: _resource_record_post_action 08:32:57 INFO - Running post-action listener: find_tests_for_verification 08:32:57 INFO - Running post-action listener: set_extra_try_arguments 08:32:57 INFO - [mozharness: 2017-08-28 15:32:57.367078Z] Finished download-and-extract step (success) 08:32:57 INFO - [mozharness: 2017-08-28 15:32:57.367194Z] Running populate-webroot step. 08:32:57 INFO - Running pre-action listener: _resource_record_pre_action 08:32:57 INFO - Running main action method: populate_webroot 08:32:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:32:57 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:32:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:32:57 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:32:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:32:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:57 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:32:57 INFO - 'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:32:57 INFO - 'tests': ['damp', 'tps']}, 08:32:57 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:32:57 INFO - 'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['damp', 'tps']}, 08:32:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:32:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:32:57 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:32:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:32:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:32:57 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:32:57 INFO - 'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:32:57 INFO - 'other-e10s': {'tests': ['a11yr', 08:32:57 INFO - 'ts_paint', 08:32:57 INFO - 'tpaint', 08:32:57 INFO - 'sessionrestore', 08:32:57 INFO - 'sessionrestore_many_windows', 08:32:57 INFO - 'sessionrestore_no_auto_restore', 08:32:57 INFO - 'tabpaint']}, 08:32:57 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['a11yr', 08:32:57 INFO - 'ts_paint', 08:32:57 INFO - 'tpaint', 08:32:57 INFO - 'sessionrestore', 08:32:57 INFO - 'sessionrestore_many_windows', 08:32:57 INFO - 'sessionrestore_no_auto_restore', 08:32:57 INFO - 'tabpaint']}, 08:32:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:32:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 08:32:57 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['bloom_basic_singleton']}, 08:32:57 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['bloom_basic']}, 08:32:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:32:57 INFO - 'tsvgr_opacity', 08:32:57 INFO - 'tart', 08:32:57 INFO - 'tscrollx', 08:32:57 INFO - 'tsvg_static']}, 08:32:57 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['tsvgx', 08:32:57 INFO - 'tsvgr_opacity', 08:32:57 INFO - 'tart', 08:32:57 INFO - 'tscrollx', 08:32:57 INFO - 'tsvg_static']}, 08:32:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:32:57 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:32:57 INFO - 'talos_options': ['--stylo'], 08:32:57 INFO - 'tests': ['tp5o']}, 08:32:57 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:32:57 INFO - 'talos_options': ['--mitmproxy', 08:32:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:32:57 INFO - '--firstNonBlankPaint'], 08:32:57 INFO - 'tests': ['tp6_google', 08:32:57 INFO - 'tp6_youtube', 08:32:57 INFO - 'tp6_amazon', 08:32:57 INFO - 'tp6_facebook']}, 08:32:57 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:32:57 INFO - 'talos_options': ['--stylo', 08:32:57 INFO - '--mitmproxy', 08:32:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:32:57 INFO - '--firstNonBlankPaint'], 08:32:57 INFO - 'tests': ['tp6_google', 08:32:57 INFO - 'tp6_youtube', 08:32:57 INFO - 'tp6_amazon', 08:32:57 INFO - 'tp6_facebook']}, 08:32:57 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:32:57 INFO - 'talos_options': ['--stylo', 08:32:57 INFO - '--stylo-threads=1', 08:32:57 INFO - '--mitmproxy', 08:32:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:32:57 INFO - '--firstNonBlankPaint'], 08:32:57 INFO - 'tests': ['tp6_google', 08:32:57 INFO - 'tp6_youtube', 08:32:57 INFO - 'tp6_amazon', 08:32:57 INFO - 'tp6_facebook']}, 08:32:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:32:57 INFO - 'talos_options': ['--xperf_path', 08:32:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:57 INFO - 'tests': ['tp5n']}, 08:32:57 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:32:57 INFO - 'talos_options': ['--stylo', 08:32:57 INFO - '--xperf_path', 08:32:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:57 INFO - 'tests': ['tp5n']}}} 08:32:57 INFO - Running post-action listener: _resource_record_post_action 08:32:57 INFO - [mozharness: 2017-08-28 15:32:57.385004Z] Finished populate-webroot step (success) 08:32:57 INFO - [mozharness: 2017-08-28 15:32:57.385113Z] Running create-virtualenv step. 08:32:57 INFO - Running pre-action listener: _resource_record_pre_action 08:32:57 INFO - Running main action method: create_virtualenv 08:32:57 INFO - Creating virtualenv /builds/slave/test/build/venv 08:32:57 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:32:57 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:32:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:32:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:32:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:32:58 INFO - Installing distribute.............................................................................................................................................................................................done. 08:32:58 INFO - Installing pip...............done. 08:32:58 INFO - Return code: 0 08:32:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:32:58 INFO - Reading from file tmpfile_stdout 08:32:58 INFO - Output received: 08:32:58 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:32:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf991f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1164a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1307f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', '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 08:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:32:58 INFO - Using env: {'DISPLAY': ':0', 08:32:58 INFO - 'HOME': '/home/cltbld', 08:32:58 INFO - 'LANG': 'en_US.UTF-8', 08:32:58 INFO - 'LANGUAGE': 'en_US:en', 08:32:58 INFO - 'LOGNAME': 'cltbld', 08:32:58 INFO - 'MAIL': '/var/mail/cltbld', 08:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:58 INFO - 'MOZ_NO_REMOTE': '1', 08:32:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:58 INFO - 'NO_EM_RESTART': '1', 08:32:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:58 INFO - 'PWD': '/builds/slave/test', 08:32:58 INFO - 'SHELL': '/bin/bash', 08:32:58 INFO - 'SHLVL': '1', 08:32:58 INFO - 'TERM': 'linux', 08:32:58 INFO - 'TMOUT': '86400', 08:32:58 INFO - 'USER': 'cltbld', 08:32:58 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:58 INFO - '_': '/tools/buildbot/bin/python'} 08:32:59 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:32:59 INFO - Downloading/unpacking pip>=1.5 08:32:59 INFO - Running setup.py egg_info for package pip 08:32:59 INFO - warning: no files found matching 'pip/cacert.pem' 08:32:59 INFO - warning: no files found matching '*.html' under directory 'docs' 08:32:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:32:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:32:59 INFO - Installing collected packages: pip 08:32:59 INFO - Found existing installation: pip 1.1 08:32:59 INFO - Uninstalling pip: 08:32:59 INFO - Successfully uninstalled pip 08:32:59 INFO - Running setup.py install for pip 08:32:59 INFO - warning: no files found matching 'pip/cacert.pem' 08:32:59 INFO - warning: no files found matching '*.html' under directory 'docs' 08:32:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:32:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:32:59 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:32:59 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:32:59 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:32:59 INFO - Successfully installed pip 08:32:59 INFO - Cleaning up... 08:32:59 INFO - Return code: 0 08:32:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:32:59 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')]} 08:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:59 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')]} 08:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:59 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 0xf991f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1164a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1307f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', '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 08:32:59 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 08:32:59 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 08:32:59 INFO - Using env: {'DISPLAY': ':0', 08:32:59 INFO - 'HOME': '/home/cltbld', 08:32:59 INFO - 'LANG': 'en_US.UTF-8', 08:32:59 INFO - 'LANGUAGE': 'en_US:en', 08:32:59 INFO - 'LOGNAME': 'cltbld', 08:32:59 INFO - 'MAIL': '/var/mail/cltbld', 08:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:59 INFO - 'MOZ_NO_REMOTE': '1', 08:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:59 INFO - 'NO_EM_RESTART': '1', 08:32:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:59 INFO - 'PWD': '/builds/slave/test', 08:32:59 INFO - 'SHELL': '/bin/bash', 08:32:59 INFO - 'SHLVL': '1', 08:32:59 INFO - 'TERM': 'linux', 08:32:59 INFO - 'TMOUT': '86400', 08:32:59 INFO - 'USER': 'cltbld', 08:32:59 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:59 INFO - '_': '/tools/buildbot/bin/python'} 08:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:00 INFO - Downloading/unpacking psutil>=3.1.1 08:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:33:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:33:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:33:01 INFO - Installing collected packages: psutil 08:33:01 INFO - Running setup.py install for psutil 08:33:01 INFO - building 'psutil._psutil_linux' extension 08:33:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:33:02 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 08:33:02 INFO - building 'psutil._psutil_posix' extension 08:33:02 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 08:33:02 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 08:33:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:33:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:33:02 INFO - Successfully installed psutil 08:33:02 INFO - Cleaning up... 08:33:02 INFO - Return code: 0 08:33:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:03 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 0xf991f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1164a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1307f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', '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 08:33:03 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 08:33:03 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 08:33:03 INFO - Using env: {'DISPLAY': ':0', 08:33:03 INFO - 'HOME': '/home/cltbld', 08:33:03 INFO - 'LANG': 'en_US.UTF-8', 08:33:03 INFO - 'LANGUAGE': 'en_US:en', 08:33:03 INFO - 'LOGNAME': 'cltbld', 08:33:03 INFO - 'MAIL': '/var/mail/cltbld', 08:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:03 INFO - 'MOZ_NO_REMOTE': '1', 08:33:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:03 INFO - 'NO_EM_RESTART': '1', 08:33:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:03 INFO - 'PWD': '/builds/slave/test', 08:33:03 INFO - 'SHELL': '/bin/bash', 08:33:03 INFO - 'SHLVL': '1', 08:33:03 INFO - 'TERM': 'linux', 08:33:03 INFO - 'TMOUT': '86400', 08:33:03 INFO - 'USER': 'cltbld', 08:33:03 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:03 INFO - '_': '/tools/buildbot/bin/python'} 08:33:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:33:03 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 08:33:03 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 08:33:03 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 08:33:03 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 08:33:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:33:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:33:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:33:04 INFO - Installing collected packages: mozsystemmonitor 08:33:04 INFO - Running setup.py install for mozsystemmonitor 08:33:04 INFO - Successfully installed mozsystemmonitor 08:33:04 INFO - Cleaning up... 08:33:04 INFO - Return code: 0 08:33:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:04 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 0xf991f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1164a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1307f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', '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 08:33:04 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 08:33:04 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 08:33:04 INFO - Using env: {'DISPLAY': ':0', 08:33:04 INFO - 'HOME': '/home/cltbld', 08:33:04 INFO - 'LANG': 'en_US.UTF-8', 08:33:04 INFO - 'LANGUAGE': 'en_US:en', 08:33:04 INFO - 'LOGNAME': 'cltbld', 08:33:04 INFO - 'MAIL': '/var/mail/cltbld', 08:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:04 INFO - 'MOZ_NO_REMOTE': '1', 08:33:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:04 INFO - 'NO_EM_RESTART': '1', 08:33:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:04 INFO - 'PWD': '/builds/slave/test', 08:33:04 INFO - 'SHELL': '/bin/bash', 08:33:04 INFO - 'SHLVL': '1', 08:33:04 INFO - 'TERM': 'linux', 08:33:04 INFO - 'TMOUT': '86400', 08:33:04 INFO - 'USER': 'cltbld', 08:33:04 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:04 INFO - '_': '/tools/buildbot/bin/python'} 08:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:04 INFO - Downloading/unpacking jsonschema==2.5.1 08:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:33:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:05 INFO - Downloading functools32-3.2.3-2.tar.gz 08:33:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:33:05 INFO - warning: no files found matching '*.txt' 08:33:05 INFO - no previously-included directories found matching 'build' 08:33:05 INFO - no previously-included directories found matching 'dist' 08:33:05 INFO - no previously-included directories found matching '.git*' 08:33:05 INFO - Installing collected packages: jsonschema, functools32 08:33:06 INFO - Running setup.py install for functools32 08:33:06 INFO - warning: no files found matching '*.txt' 08:33:06 INFO - no previously-included directories found matching 'build' 08:33:06 INFO - no previously-included directories found matching 'dist' 08:33:06 INFO - no previously-included directories found matching '.git*' 08:33:06 INFO - Successfully installed jsonschema functools32 08:33:06 INFO - Cleaning up... 08:33:06 INFO - Return code: 0 08:33:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:33: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')]} 08:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33: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')]} 08:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:06 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 0xf991f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1164a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1307f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', '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 08:33:06 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 08:33:06 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 08:33:06 INFO - Using env: {'DISPLAY': ':0', 08:33:06 INFO - 'HOME': '/home/cltbld', 08:33:06 INFO - 'LANG': 'en_US.UTF-8', 08:33:06 INFO - 'LANGUAGE': 'en_US:en', 08:33:06 INFO - 'LOGNAME': 'cltbld', 08:33:06 INFO - 'MAIL': '/var/mail/cltbld', 08:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:06 INFO - 'MOZ_NO_REMOTE': '1', 08:33:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:06 INFO - 'NO_EM_RESTART': '1', 08:33:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:06 INFO - 'PWD': '/builds/slave/test', 08:33:06 INFO - 'SHELL': '/bin/bash', 08:33:06 INFO - 'SHLVL': '1', 08:33:06 INFO - 'TERM': 'linux', 08:33:06 INFO - 'TMOUT': '86400', 08:33:06 INFO - 'USER': 'cltbld', 08:33:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:06 INFO - '_': '/tools/buildbot/bin/python'} 08:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:33:06 INFO - Cleaning up... 08:33:06 INFO - Return code: 0 08:33:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:33: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')]} 08:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33: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')]} 08:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:06 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 0xf991f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1164a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1307f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', '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 08:33:06 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 08:33:06 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 08:33:06 INFO - Using env: {'DISPLAY': ':0', 08:33:06 INFO - 'HOME': '/home/cltbld', 08:33:06 INFO - 'LANG': 'en_US.UTF-8', 08:33:06 INFO - 'LANGUAGE': 'en_US:en', 08:33:06 INFO - 'LOGNAME': 'cltbld', 08:33:06 INFO - 'MAIL': '/var/mail/cltbld', 08:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:06 INFO - 'MOZ_NO_REMOTE': '1', 08:33:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:06 INFO - 'NO_EM_RESTART': '1', 08:33:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:06 INFO - 'PWD': '/builds/slave/test', 08:33:06 INFO - 'SHELL': '/bin/bash', 08:33:06 INFO - 'SHLVL': '1', 08:33:06 INFO - 'TERM': 'linux', 08:33:06 INFO - 'TMOUT': '86400', 08:33:06 INFO - 'USER': 'cltbld', 08:33:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:06 INFO - '_': '/tools/buildbot/bin/python'} 08:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:06 INFO - Downloading/unpacking blobuploader==1.2.4 08:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:10 INFO - Downloading blobuploader-1.2.4.tar.gz 08:33:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:33:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:33:10 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 08:33:10 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 08:33:10 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 08:33:10 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 08:33:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:33:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:33:11 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 08:33:11 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 08:33:11 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 08:33:11 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 08:33:11 INFO - Downloading docopt-0.6.1.tar.gz 08:33:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:33:11 INFO - Installing collected packages: blobuploader, requests, docopt 08:33:11 INFO - Running setup.py install for blobuploader 08:33:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:33:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:33:11 INFO - Running setup.py install for requests 08:33:11 INFO - Running setup.py install for docopt 08:33:11 INFO - Successfully installed blobuploader requests docopt 08:33:11 INFO - Cleaning up... 08:33:11 INFO - Return code: 0 08:33:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33:11 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')]} 08:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:11 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')]} 08:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:11 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 0xf991f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1164a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1307f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', '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 08:33:11 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 08:33:11 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 08:33:11 INFO - Using env: {'DISPLAY': ':0', 08:33:11 INFO - 'HOME': '/home/cltbld', 08:33:11 INFO - 'LANG': 'en_US.UTF-8', 08:33:11 INFO - 'LANGUAGE': 'en_US:en', 08:33:11 INFO - 'LOGNAME': 'cltbld', 08:33:11 INFO - 'MAIL': '/var/mail/cltbld', 08:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:11 INFO - 'MOZ_NO_REMOTE': '1', 08:33:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:11 INFO - 'NO_EM_RESTART': '1', 08:33:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:11 INFO - 'PWD': '/builds/slave/test', 08:33:11 INFO - 'SHELL': '/bin/bash', 08:33:11 INFO - 'SHLVL': '1', 08:33:11 INFO - 'TERM': 'linux', 08:33:11 INFO - 'TMOUT': '86400', 08:33:11 INFO - 'USER': 'cltbld', 08:33:11 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:11 INFO - '_': '/tools/buildbot/bin/python'} 08:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:33:12 INFO - Running setup.py (path:/tmp/pip-29HamW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:33:12 INFO - Running setup.py (path:/tmp/pip-DgjPxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:33:12 INFO - Running setup.py (path:/tmp/pip-DnnqYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:33:12 INFO - Running setup.py (path:/tmp/pip-9nkRJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:33:12 INFO - Running setup.py (path:/tmp/pip-GVu_wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:33:12 INFO - Running setup.py (path:/tmp/pip-NhLMkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:33:12 INFO - Running setup.py (path:/tmp/pip-MDeeLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:33:12 INFO - Running setup.py (path:/tmp/pip-jlD9wM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:33:12 INFO - Running setup.py (path:/tmp/pip-MllM6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:33:12 INFO - Running setup.py (path:/tmp/pip-tcTWO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:33:12 INFO - Running setup.py (path:/tmp/pip-iWP3JW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:33:12 INFO - Running setup.py (path:/tmp/pip-KHgsEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:33:12 INFO - Running setup.py (path:/tmp/pip-n1WDTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:33:12 INFO - Running setup.py (path:/tmp/pip-5WGXAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:13 INFO - Running setup.py (path:/tmp/pip-SYEvPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:33:13 INFO - Running setup.py (path:/tmp/pip-lfwhvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:33:13 INFO - Running setup.py (path:/tmp/pip-iag8jF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:33:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:33:13 INFO - Running setup.py install for manifestparser 08:33:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:33:13 INFO - Running setup.py install for mozcrash 08:33:13 INFO - Running setup.py install for mozdebug 08:33:13 INFO - Running setup.py install for mozdevice 08:33:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:33:13 INFO - Running setup.py install for mozfile 08:33:13 INFO - Running setup.py install for mozhttpd 08:33:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:33:13 INFO - Running setup.py install for mozinfo 08:33:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:33:13 INFO - Running setup.py install for mozInstall 08:33:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Running setup.py install for mozleak 08:33:14 INFO - Running setup.py install for mozlog 08:33:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Running setup.py install for moznetwork 08:33:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Running setup.py install for mozprocess 08:33:14 INFO - Running setup.py install for mozprofile 08:33:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Running setup.py install for mozrunner 08:33:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Running setup.py install for mozscreenshot 08:33:14 INFO - Running setup.py install for moztest 08:33:14 INFO - Running setup.py install for mozversion 08:33:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:33:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:33:14 INFO - Cleaning up... 08:33:15 INFO - Return code: 0 08:33:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33:15 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')]} 08:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:15 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')]} 08:33:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:15 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 0xf991f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1164a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1307f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', '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 08:33:15 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 08:33:15 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 08:33:15 INFO - Using env: {'DISPLAY': ':0', 08:33:15 INFO - 'HOME': '/home/cltbld', 08:33:15 INFO - 'LANG': 'en_US.UTF-8', 08:33:15 INFO - 'LANGUAGE': 'en_US:en', 08:33:15 INFO - 'LOGNAME': 'cltbld', 08:33:15 INFO - 'MAIL': '/var/mail/cltbld', 08:33:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:15 INFO - 'MOZ_NO_REMOTE': '1', 08:33:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:15 INFO - 'NO_EM_RESTART': '1', 08:33:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:15 INFO - 'PWD': '/builds/slave/test', 08:33:15 INFO - 'SHELL': '/bin/bash', 08:33:15 INFO - 'SHLVL': '1', 08:33:15 INFO - 'TERM': 'linux', 08:33:15 INFO - 'TMOUT': '86400', 08:33:15 INFO - 'USER': 'cltbld', 08:33:15 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:33:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:15 INFO - '_': '/tools/buildbot/bin/python'} 08:33:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:33:15 INFO - Running setup.py (path:/tmp/pip-dsiMb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:33:15 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)) 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:33:15 INFO - Running setup.py (path:/tmp/pip-mI204C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:33:15 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)) 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:33:15 INFO - Running setup.py (path:/tmp/pip-JVQAgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:33:15 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)) 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:33:15 INFO - Running setup.py (path:/tmp/pip-dlVizs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:33:15 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)) 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:33:15 INFO - Running setup.py (path:/tmp/pip-6Yc3kZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:33:15 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)) 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:33:15 INFO - Running setup.py (path:/tmp/pip-6sTxNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:33:15 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)) 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:33:15 INFO - Running setup.py (path:/tmp/pip-7yaS6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:33:15 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)) 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:33:15 INFO - Running setup.py (path:/tmp/pip-93YBsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:33:15 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)) 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:33:15 INFO - Running setup.py (path:/tmp/pip-lep4gF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:33:15 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)) 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:33:15 INFO - Running setup.py (path:/tmp/pip-pvB3si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:33:15 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)) 08:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:33:15 INFO - Running setup.py (path:/tmp/pip-iglpyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:33:16 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)) 08:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:33:16 INFO - Running setup.py (path:/tmp/pip-XQPT2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:33:16 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)) 08:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:33:16 INFO - Running setup.py (path:/tmp/pip-seHbwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:33:16 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)) 08:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:33:16 INFO - Running setup.py (path:/tmp/pip-QXXqLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:33:16 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)) 08:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:16 INFO - Running setup.py (path:/tmp/pip-SQhg93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:16 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)) 08:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:33:16 INFO - Running setup.py (path:/tmp/pip-S5rYd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:33:16 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)) 08:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:33:16 INFO - Running setup.py (path:/tmp/pip-iFpikY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:33:16 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)) 08:33:16 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)) 08:33:16 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)) 08:33:16 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)) 08:33:16 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)) 08:33:16 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)) 08:33:16 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)) 08:33:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:17 INFO - Downloading blessings-1.6.tar.gz 08:33:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:33:17 INFO - Installing collected packages: blessings 08:33:17 INFO - Running setup.py install for blessings 08:33:17 INFO - Successfully installed blessings 08:33:17 INFO - Cleaning up... 08:33:17 INFO - Return code: 0 08:33:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:33:18 INFO - Reading from file tmpfile_stdout 08:33:18 INFO - Current package versions: 08:33:18 INFO - argparse == 1.2.1 08:33:18 INFO - blessings == 1.6 08:33:18 INFO - blobuploader == 1.2.4 08:33:18 INFO - docopt == 0.6.1 08:33:18 INFO - functools32 == 3.2.3-2 08:33:18 INFO - jsonschema == 2.5.1 08:33:18 INFO - manifestparser == 1.1 08:33:18 INFO - mozInstall == 1.13 08:33:18 INFO - mozcrash == 1.0 08:33:18 INFO - mozdebug == 0.1 08:33:18 INFO - mozdevice == 0.51 08:33:18 INFO - mozfile == 1.2 08:33:18 INFO - mozhttpd == 0.7 08:33:18 INFO - mozinfo == 0.10 08:33:18 INFO - mozleak == 0.1 08:33:18 INFO - mozlog == 3.5 08:33:18 INFO - moznetwork == 0.27 08:33:18 INFO - mozprocess == 0.25 08:33:18 INFO - mozprofile == 0.28 08:33:18 INFO - mozrunner == 6.13 08:33:18 INFO - mozscreenshot == 0.1 08:33:18 INFO - mozsystemmonitor == 0.3 08:33:18 INFO - moztest == 0.8 08:33:18 INFO - mozversion == 1.4 08:33:18 INFO - psutil == 3.1.1 08:33:18 INFO - requests == 1.2.3 08:33:18 INFO - wsgiref == 0.1.2 08:33:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:18 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 0xf991f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1164a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1307f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', '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 08:33:18 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 08:33:18 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 08:33:18 INFO - Using env: {'DISPLAY': ':0', 08:33:18 INFO - 'HOME': '/home/cltbld', 08:33:18 INFO - 'LANG': 'en_US.UTF-8', 08:33:18 INFO - 'LANGUAGE': 'en_US:en', 08:33:18 INFO - 'LOGNAME': 'cltbld', 08:33:18 INFO - 'MAIL': '/var/mail/cltbld', 08:33:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:18 INFO - 'MOZ_NO_REMOTE': '1', 08:33:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:18 INFO - 'NO_EM_RESTART': '1', 08:33:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:18 INFO - 'PWD': '/builds/slave/test', 08:33:18 INFO - 'SHELL': '/bin/bash', 08:33:18 INFO - 'SHLVL': '1', 08:33:18 INFO - 'TERM': 'linux', 08:33:18 INFO - 'TMOUT': '86400', 08:33:18 INFO - 'USER': 'cltbld', 08:33:18 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:33:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:18 INFO - '_': '/tools/buildbot/bin/python'} 08:33:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:18 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)) 08:33:18 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)) 08:33:18 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)) 08:33:18 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)) 08:33:18 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)) 08:33:18 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)) 08:33:18 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)) 08:33:18 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)) 08:33:18 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)) 08:33:18 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:33:18 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 08:33:18 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 08:33:18 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 08:33:18 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 08:33:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:33:19 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)) 08:33:19 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)) 08:33:19 INFO - Installing collected packages: simplejson 08:33:19 INFO - Running setup.py install for simplejson 08:33:19 INFO - building 'simplejson._speedups' extension 08:33:19 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 08:33:20 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 08:33:20 INFO - Successfully installed simplejson 08:33:20 INFO - Cleaning up... 08:33:20 INFO - Return code: 0 08:33:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:20 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 0xf991f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1164a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1307f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', '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 08:33:20 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 08:33:20 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 08:33:20 INFO - Using env: {'DISPLAY': ':0', 08:33:20 INFO - 'HOME': '/home/cltbld', 08:33:20 INFO - 'LANG': 'en_US.UTF-8', 08:33:20 INFO - 'LANGUAGE': 'en_US:en', 08:33:20 INFO - 'LOGNAME': 'cltbld', 08:33:20 INFO - 'MAIL': '/var/mail/cltbld', 08:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:20 INFO - 'MOZ_NO_REMOTE': '1', 08:33:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:20 INFO - 'NO_EM_RESTART': '1', 08:33:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:20 INFO - 'PWD': '/builds/slave/test', 08:33:20 INFO - 'SHELL': '/bin/bash', 08:33:20 INFO - 'SHLVL': '1', 08:33:20 INFO - 'TERM': 'linux', 08:33:20 INFO - 'TMOUT': '86400', 08:33:20 INFO - 'USER': 'cltbld', 08:33:20 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:20 INFO - '_': '/tools/buildbot/bin/python'} 08:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:33:20 INFO - Cleaning up... 08:33:20 INFO - Return code: 0 08:33:20 INFO - Running post-action listener: _resource_record_post_action 08:33:20 INFO - Running post-action listener: _start_resource_monitoring 08:33:20 INFO - Starting resource monitoring. 08:33:20 INFO - [mozharness: 2017-08-28 15:33:20.750463Z] Finished create-virtualenv step (success) 08:33:20 INFO - [mozharness: 2017-08-28 15:33:20.751426Z] Running install step. 08:33:20 INFO - Running pre-action listener: _resource_record_pre_action 08:33:20 INFO - Running main action method: install 08:33:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:33:20 INFO - Reading from file tmpfile_stdout 08:33:20 INFO - Detecting whether we're running mozinstall >=1.0... 08:33:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:33:21 INFO - Reading from file tmpfile_stdout 08:33:21 INFO - Output received: 08:33:21 INFO - Usage: mozinstall [options] installer 08:33:21 INFO - Options: 08:33:21 INFO - -h, --help show this help message and exit 08:33:21 INFO - -d DEST, --destination=DEST 08:33:21 INFO - Directory to install application into. [default: 08:33:21 INFO - "/builds/slave/test"] 08:33:21 INFO - --app=APP Application being installed. [default: firefox] 08:33:21 INFO - mkdir: /builds/slave/test/build/application 08:33:21 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'] 08:33:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:33:38 INFO - Reading from file tmpfile_stdout 08:33:38 INFO - Output received: 08:33:38 INFO - /builds/slave/test/build/application/firefox/firefox 08:33:38 INFO - Running post-action listener: _resource_record_post_action 08:33:38 INFO - [mozharness: 2017-08-28 15:33:38.776823Z] Finished install step (success) 08:33:38 INFO - [mozharness: 2017-08-28 15:33:38.776972Z] Running setup-mitmproxy step. 08:33:38 INFO - Running pre-action listener: _resource_record_pre_action 08:33:38 INFO - Running main action method: setup_mitmproxy 08:33:38 INFO - Skipping: mitmproxy is not required 08:33:38 INFO - Running post-action listener: _resource_record_post_action 08:33:38 INFO - [mozharness: 2017-08-28 15:33:38.777808Z] Finished setup-mitmproxy step (success) 08:33:38 INFO - [mozharness: 2017-08-28 15:33:38.778028Z] Running run-tests step. 08:33:38 INFO - Running pre-action listener: _resource_record_pre_action 08:33:38 INFO - Running pre-action listener: _set_gcov_prefix 08:33:38 INFO - Running main action method: run_tests 08:33:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:33:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:33:38 INFO - Python 2.7.3 08:33:38 INFO - Return code: 0 08:33:38 INFO - grabbing minidump binary from tooltool 08:33:38 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')]} 08:33:38 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 0x1305130>, '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 0x1306030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13064c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:33:38 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 08:33:38 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 08:33:38 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 08:33:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:33:38 INFO - Return code: 0 08:33:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:33:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:33:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:33:38 INFO - ENV: RUST_BACKTRACE is now full 08:33:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:33:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:33:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 08:33:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 08:33:38 INFO - Using env: {'DISPLAY': ':0', 08:33:38 INFO - 'HOME': '/home/cltbld', 08:33:38 INFO - 'LANG': 'en_US.UTF-8', 08:33:38 INFO - 'LANGUAGE': 'en_US:en', 08:33:38 INFO - 'LOGNAME': 'cltbld', 08:33:38 INFO - 'MAIL': '/var/mail/cltbld', 08:33:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:33:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:33:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:38 INFO - 'MOZ_NO_REMOTE': '1', 08:33:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:33:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:38 INFO - 'NO_EM_RESTART': '1', 08:33:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:38 INFO - 'PWD': '/builds/slave/test', 08:33:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:33:38 INFO - 'RUST_BACKTRACE': 'full', 08:33:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:33:38 INFO - 'SHELL': '/bin/bash', 08:33:38 INFO - 'SHLVL': '1', 08:33:38 INFO - 'TERM': 'linux', 08:33:38 INFO - 'TMOUT': '86400', 08:33:38 INFO - 'USER': 'cltbld', 08:33:38 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:33:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:38 INFO - '_': '/tools/buildbot/bin/python'} 08:33:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 08:33:39 INFO - mozversion application_buildid: 20170828144704 08:33:39 INFO - mozversion application_changeset: d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 08:33:39 INFO - mozversion application_display_name: Nightly 08:33:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:33:39 INFO - mozversion application_name: Firefox 08:33:39 INFO - mozversion application_remotingname: firefox 08:33:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:33:39 INFO - mozversion application_vendor: Mozilla 08:33:39 INFO - mozversion application_version: 57.0a1 08:33:39 INFO - mozversion platform_buildid: 20170828144704 08:33:39 INFO - mozversion platform_changeset: d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 08:33:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:33:39 INFO - mozversion platform_version: 57.0a1 08:33:39 INFO - using testdate: 1503934419 08:33:39 INFO - actual date: 1503934419 08:33:39 INFO - starting webserver on 'localhost:50007' 08:33:39 INFO - SUITE-START | Running 2 tests 08:33:39 INFO - TEST-START | dromaeo_css 08:33:39 INFO - Initialising browser for dromaeo_css test... 08:33:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50007/getInfo.html -profile /tmp/tmp9u7fbY/profile 08:33:39 INFO - TEST-INFO | started process 3445 (/builds/slave/test/build/application/firefox/firefox http://localhost:50007/getInfo.html) 08:33:53 INFO - TEST-INFO | 3445: exit 0 08:33:53 INFO - Browser initialized. 08:33:53 INFO - Running cycle 1/1 for dromaeo_css test... 08:33:53 INFO - Using env: {'DISPLAY': ':0', 08:33:53 INFO - 'HOME': '/home/cltbld', 08:33:53 INFO - 'JSGC_DISABLE_POISONING': '1', 08:33:53 INFO - 'LANG': 'en_US.UTF-8', 08:33:53 INFO - 'LANGUAGE': 'en_US:en', 08:33:53 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:33:53 INFO - 'LOGNAME': 'cltbld', 08:33:53 INFO - 'MAIL': '/var/mail/cltbld', 08:33:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:33:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:33:53 INFO - 'MOZ_CRASHREPORTER': '1', 08:33:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:53 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:33:53 INFO - 'MOZ_NO_REMOTE': '1', 08:33:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:33:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:53 INFO - 'NO_EM_RESTART': '1', 08:33:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:53 INFO - 'PWD': '/builds/slave/test', 08:33:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:33:53 INFO - 'RUST_BACKTRACE': 'full', 08:33:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:33:53 INFO - 'SHELL': '/bin/bash', 08:33:53 INFO - 'SHLVL': '1', 08:33:53 INFO - 'TERM': 'linux', 08:33:53 INFO - 'TMOUT': '86400', 08:33:53 INFO - 'USER': 'cltbld', 08:33:53 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:33:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:53 INFO - '_': '/tools/buildbot/bin/python'} 08:33:53 INFO - TEST-INFO | started process 3618 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9u7fbY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:33:54 INFO - PID 3618 | 08:33:54 INFO - PID 3618 | (/builds/slave/test/build/application/firefox/firefox:3669): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:33:54 INFO - PID 3618 | 08:34:04 INFO - PID 3618 | RSS: Main: 172732416 08:34:04 INFO - PID 3618 | 08:37:04 INFO - PID 3618 | Cycle 1(1): loaded http://localhost:50007/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50007/tests/dromaeo/cssquery-ext.html) 08:37:05 INFO - PID 3618 | RSS: Main: 175456256 08:37:05 INFO - PID 3618 | 08:40:06 INFO - PID 3618 | Cycle 1(1): loaded http://localhost:50007/tests/dromaeo/cssquery-ext.html (next: http://localhost:50007/tests/dromaeo/cssquery-jquery.html) 08:40:07 INFO - PID 3618 | RSS: Main: 171483136 08:40:07 INFO - PID 3618 | 08:43:09 INFO - PID 3618 | Cycle 1(1): loaded http://localhost:50007/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50007/tests/dromaeo/cssquery-mootools.html) 08:43:09 INFO - PID 3618 | RSS: Main: 172122112 08:43:09 INFO - PID 3618 | 08:46:11 INFO - PID 3618 | Cycle 1(1): loaded http://localhost:50007/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50007/tests/dromaeo/cssquery-prototype.html) 08:46:12 INFO - PID 3618 | RSS: Main: 172711936 08:46:12 INFO - PID 3618 | 08:49:15 INFO - PID 3618 | Cycle 1(1): loaded http://localhost:50007/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50007/tests/dromaeo/cssquery-yui.html) 08:49:16 INFO - PID 3618 | RSS: Main: 173490176 08:49:16 INFO - PID 3618 | 08:52:18 INFO - PID 3618 | Cycle 1(1): loaded http://localhost:50007/tests/dromaeo/cssquery-yui.html (next: http://localhost:50007/tests/dromaeo/cssquery-dojo.html) 08:52:18 INFO - PID 3618 | RSS: Main: 175853568 08:52:18 INFO - PID 3618 | 08:52:18 INFO - PID 3618 | __start_tp_report 08:52:18 INFO - PID 3618 | _x_x_mozilla_page_load 08:52:18 INFO - PID 3618 | _x_x_mozilla_page_load_details 08:52:18 INFO - PID 3618 | |i|pagename|runs| 08:52:18 INFO - PID 3618 | |0;dojo.html;3332.9000129996093;3337.2324365395953;3349.6315405305418;3361.1933136047323;3364.1757769346514;6302;6353.555251132417;6402.135711678922;6425.357464253578;6509.349065093488;6265.279492858323;6338.271098823625;6371.108044873721;6373.394527519889;6382.968085159568;5777.45114214149;5809.6804675742715;5838.883222335551;5848.2982042155;5895.734691938873;5914.497267732248;5945.16767652529;5958.553108516857;5974.074018527113;5994.700264986755;5745.731649463432;5804.535637149018;5866.911996320058;5917.644941303514;5963.642181469104;5792.797252096157;5801.50687191585;5855;5881.47066763989;5934.495567876693;5794.5943783935145;5836.171535114978;5864.472197502245;5905.43898329658;5914.142449344864;6234.719437625317;6235.844103897393;6248.250209974786;6260.530460215502;6443.806685799386;6742.258351581322;6758.05387245786;6761.9323806761795;6832.829179270509;6844.1444819397575;6888.517803753739;6908.827279318007;6931.965340173267;6941.375276225113;6945.617991010492;6309;6344.048392741126;6405.647689377082;6418.454431373339;6429.03564465334;6891.689873955684;6902.861942761117;6978.267281935324;7096.396806271421;7102.644867756591;6667.899981500282;6672.83317917056;6673.432758215606;6681.5656982296;6701.832454188684;6773.8983915241315;6788.422984046312;6798.898016529756;6883.65581720922;6887.724491020404;6809.863802723918;6852.177738671391;6854.177498700188;6855.760048398282;6869.725210991504;6772.5936443813525;6773.830654233683;6805.421539169127;6889.345512176335;6909.481788865855;5306.52241298285;5361.35663720356;5378.327709036371;5461.1262198048125;5465.617406781566;4751.168545504523;4771.260454629538;4779.330893674888;4812.831550895702;4817.759112044454;6001.279846418458;6068;6070.484008859208;6109.053096770008;6114.6942652868065;6793.150856142982;6851.554648947803;6862.553933994274;6936.167659880846;6954.4436445084275;5830.387809279964;5971.462568368867;5972.313183983811;6045.21412216409;6068.787592434243;5929.822105336847;5951.9107213391835;5963.4334738199805;5967.761289548456;5968.612040217372;6568.737250510022;6625.204975402982;6628.436582890508;6631.701573429207;6651.567648102858;6846.520743547904;6853.074834897225;6877.931220687929;6948.478864085113;6956.095707557985;6432.195975503062;6566.6388348641285;6571.211454625475;6660.167479065117;6675.299093595103;6684.298148694317;6699.39705426514;6727.865442691021;6762.932370676231;6765.1205343305055;6817.147856517935;6867.553609015398;6875.07186529812;6893.862122757617;6912.101426814482;6536.084948107364;6581;6626.900596491153;6633.867322653617;6680.732770689312;6556.409923106943;6563.474922006323;6594.637294948824;6644.966775166094;6681.33186681328;6593.241777195684;6598.670066496752;6604.636744979072;6610.10763546915;6628.867422651423;6635.867282654223;6653.268140504444;6734.9224124141865;6750.831229219309;6781.728730850711;6419.390157934989;6463.030545418225;6465.353464653497;6547.672616369067;6555.180602424697 08:52:18 INFO - PID 3618 | |1;ext.html;844.560828369248;866.9219770220681;898.0615257056376;898.2813749000798;1133.257716195892;30860.69139308606;31438.371232575362;31493.84253078737;41512.16975660485;42165.57834421655;42072.78963605178;42569.574304256945;42626.8686565672;42645.36031959515;42655.78672106635;7859.960700196491;7950.1254861965135;8058.758237252891;8063.758087257363;8130.308923741489;7888.684452621888;7970.8804367934235;7989;8012.63943122558;8021.759347219593;7959.960200198991;8014.5591992440395;8024.919750802475;8043.879341809878;8052.396070294752;7956.204379562056;7999.96000020002;8001.599920003977;8013.839723205532;8028.558429286387;7923.366130709529;7990.360771138353;7995.520268783834;7998.960005199937;7999;36382.454263186075;36435.089122771875;36577.45133822995;36627.4505882412;36632.45051324232;3411.727061835047;3435.038189306998;3435.57055368079;3440.9827950860085;3444.018454740412;31181.532277015864;31229.53155702666;31284.217894552592;31346;31382.372352553048;5080.568151707109;5185.870353241162;5200.323957885451;5222.608304377187;5261.815836445744;183822;184429;184986;185098.07450962794;185732;44359.112817743466;44435.333469997975;44574.33138502925;48342.75828620834;48736.51263487391;50866.237006444935;50876;51062.74468627633;51125;51165;16163;16176.43382481631;16203.270852811416;16434.75347869783;16449.917750411412;13430.194188348534;13501.257430841202;13514.932425338007;13524.391402386917;13543.59369218925;13631.113977591425;13682.247476388695;13740.450381984609;13894.861051389558;13946.930265348608;5664.5751568631695;5666.34836993751;5666.891503659814;5674.120511320752;5687.203791469197;984.3847595252967;985.5318723606373;998.5356809083811;999.1507218863908;1010.408910787183;22690.659640105412;22703.432414189778;22765.203217887625;22802.543949120925;22904.312870613907;1105.9612913548149;1108.2076315434342;1116.330201878866;1120.3501968858207;1122.6126986189752;12182.390880455941;12306.138570300172;12315.50737970489;12344.074194435274;12366.75266494665;233902;237256;237311;237405;237415;51121;51255;51451;52911.20633190428;56079;1387.257817067864;1417.1638733147286;1417.4755340524073;1420.0982376190987;1437.6118448018976;26519.734802651725;26841;27508.724912750615;27640.58539121955;27849.860750696116;38436.23127537489;38810.80594597009;38868.80565597267;38908;39030.2193956125;209613;213221;213313;213332;213710;36872.81563592165;36917;36938;37090.07274818151;37349;44482.3327650079;45677.314840278086;45687.31469027901;45755.398561050206;46119.308210377545;33684.15789605279;33746.83126584352;33931.66068339285;33975.830120849234;34282.82858585791;32954;33295.83352083224;33555.83222083874;33734;34258.48612270768;37617.62382376141;37626.81186594049;37709.811450943664;37716.62283377241;38411.6158838408;33316.33367332591;33409.83295083509;33743;33782;33813.3237335247;23583.410414739083;23701.170459033852;23800.642990355504;23903.52192956166;23990.400239993443 08:52:18 INFO - PID 3618 | |2;jquery.html;555.8082461550766;563.6167406163808;565.2510423688612;565.9434056594339;585.2947198625656;21900.23349182777;22337;22634.54730905381;22662.546749065008;22772.77227227727;24172.75827241727;24704.135355262526;24705.505889882235;24720.62919056211;24742.25773226806;6706.295838936914;6811.557248778839;6833.589984600915;6836.726530938757;6856;5914.378990206031;6451.967685170373;6452.096706461076;6469.4500967417825;6482.189726284215;6426.196725409324;6435.742570297182;6437.195350581177;6442.098106265126;6448.742050317982;6414.967925160391;6416.262129855057;6418.93581064188;6425.164728585278;6428.710708018127;5938.910916336258;5961.5230781537375;5965.55258355625;5969.791057312973;5981.252343457068;28046.298842528897;28723.138305850858;28992.13023609274;29074.70925290741;29188.708112918812;2005.1377907499768;2039.9592008159902;2044.1108117968731;2044.5399785048394;2045.5193029638024;19232.519187020298;19453.80546194548;19753.50616234591;19872.403827885188;20127;2431.4924746657007;2435.975640243593;2465.9506809863883;2471.270975062361;2483.1681386735463;136134;139165;139428;140012.99999999898;140022;14123.43506259738;14635.707285854223;14873.33070011852;14912.776308355167;14915.179665118523;22915.7708422917;22927.88536057309;23017.42456438569;23035.308940731804;23074;9922.355046921926;10016.098551130433;10113.64602238918;10178.592856285666;10249.897501025043;8242;8765.07966663504;9476.431414115175;9481.4785186814;9501.714948551555;8471.491710497266;9110.453372797654;9136.543172841331;9225.769355766159;9276.489793061446;2601.310652677042;2611.242739605493;2612.438325759933;2614.437895852363;2748.7938404619736;918.86676431917;950.12588418655;961.8893827209961;971.8493633486821;980.5832521178471;18880.527986800163;18948.40972451205;18982.810171898105;19092.80907190938;19458.027098645012;989.366805244644;1011.6256984915628;1022.9693109206736;1029.6344797596896;1034.0590063042596;9535.332526723063;9603.807923841485;9738.46438445878;9846.163076138608;9881.456519891475;483261;495844;509018;514469;536140;118860;125991;126358;129317.35341323233;129598;1223.8286639870425;1235.5490246060124;1263.1663102352404;1264.2667252993242;1266.499732605634;15915.761263581287;16360.754588681417;16361.50644300999;16416.753748694016;16445.67108657796;55211.723941380034;55247.17129243145;55847.441525584225;57315.71342143263;57657;72850.63574682093;74279;74397;75157.24842751722;75201.6239918797;11947.581834635746;12383.876161238271;14210.78683819723;17622.647547049244;20980.370588882357;22888;23547.293581192593;23601.763982359957;23915.402114947265;24323.148689795773;18012.54968625795;18607.627847443247;18871;18893.149808258935;18901.338453154072;16751.329946802478;17109.914450427666;17414.129293535017;17608.55978600494;17738.91130544339;18185.818141818414;18338.174782135102;19060.1422935971;19219.615607688047;19234.23063077461;20383.388498345073;20449.89775051115;20755.066022028743;20870;21128.78871211268;18531.258749649864;18561.53596160107;18583.81416185875;18785;18819.71770423472 08:52:18 INFO - PID 3618 | |3;mootools.html;1336.6190635668834;1381.7443772902013;1390.3743315508025;1391.8468968413467;1401.068289587424;14474.638134046641;14765.77851332231;15062.92468537657;15083.396664133448;15110.168940708256;14972.625684357896;15101.791856651469;15641;15690.529284121494;15709.057456552615;2543.682039745032;2617.842929424236;2668.533006723825;2669.866506674668;2671.5591927331966;2656.667916510436;2684.3289177705574;2758.7379198976064;2840.5597132444504;2847.530157524006;2796.4127533218048;2816.633837601109;2833.7874659400522;2835.4754370441433;2844.075675405491;2577.136659219164;2732.2076597786618;2743.2593199836133;2756.2695885590333;2758.9310267243377;2675.959860602073;2684.9463010739873;2685.731426857318;2686.489566982267;2689.690685571165;14271.429142834275;14586.635334116625;14597.70804583913;14605.707885842223;14617.122972621677;7528.8117797054965;7607.885881711723;7784.455088143832;7800.375969922449;7800.960995195044;5350.678959262418;5479.890402191933;5536.557075433996;5556.222128901916;5576.888462230732;1891.7351570780102;1938.185961896007;1940.5051711813396;1980.7425034745536;1993.760748710164;24908.626370604456;25128.497430051295;25130.371740706447;25132.371690707696;25165.74834251671;6519.315471875385;6530.8040758777315;6542.476601871839;6713.664316784141;6789.725441976894;16599.585010374838;16630.58423539397;16646;16653.91673041627;16670.083145427117;7641.2740789624895;7766.339861111869;7782.883256751154;7791.142974272825;7793.610319484003;6198.845028874314;7174.569525828467;7199.280071992864;7438.66526006331;7462.925370746331;4762.357081793983;5228.398734145546;5348.893022139536;5351.759170837322;5357.892842143136;1535.8080239970004;1561.9375224990872;1563.5543869996995;1579.88940774147;1583.9841601584067;866.5667166416791;880.2737741363401;891.1445012787797;893.9821203575892;899.2536194958169;7846.764597062097;8077.717279895293;8108.797280067927;8152.388570857091;8189.426740128253;914.5930061122737;931.5016466190556;953.6948176583566;983.680303901235;989.5200827598609;4326.502452217972;4381.890452738643;4618.584327410549;4943.28322393251;5030.245463180552;19738.40784776459;19873.503162420766;19897.204111835363;20302.69545956782;20690.68963965542;19881.105350258982;20091.698624520643;20249.49376265576;20271.4932126698;20445.386638400872;11054.281471704475;11069.114470842313;11255.099592032619;11259.155563332783;11261.549538018387;7429.591372474566;7690.230976902265;7761.68953241864;7805.33654639362;7809.843803123792;17462.47612571625;17810.376636817648;17997.910010449865;18249.270029198684;18337.358192463198;18129;18197.090145492937;18247.81752182461;18284.908575457037;18332;10531.157507399543;10868.18488613373;10868.565257389932;10872.075873550837;10879.1840611956;11350.205485615928;11946.581869634521;12029.218100823418;12040.879591203975;13504.86495135036;9982.850257246293;9985.251106166921;10014.799704005733;10114.747131321776;10186.745331366776;10526.473676316307;10600.363978161335;10616.734581635521;10776;10919.344839309666;9137.680181193626;9250.814983700422;9255.213306868991;9255.583498742708;9256;6758.087658166085;6789.15135608049;6810.31896810315;6819.761308354184;6831.351021652935;6637.203535575569;6769.018492318684;6786.253512113565;6889.724411023703;6937.479689023445 08:52:18 INFO - PID 3618 | |4;prototype.html;497.5100111695875;500.4897159647406;511.108116336992;519.6985748266006;521.4290352064488;6470.288268290485;6490.870182596357;6508.707108180131;6518.641474718895;6521.217453905538;6187.783427580036;6271.937280627192;6289.654069026202;6328.08242804793;6362.586431881936;5077.84766457005;5092.745362731848;5110.821121260757;5120.308758317617;5145.81989630363;5000.749962501878;5013.248012798092;5023.372078490189;5042.722650254234;5065.189569668853;4818.108649899762;4915.975420122894;5004.097623378432;5025.597952163836;5035.194368900977;4929.507049295078;4987.800487980477;4999.350084488993;5000.749962501878;5047.2176812593925;4446.509490050327;4448.911021779546;4470.8435204768;4472.910541789179;4495.370648109268;6198.876022479525;6201.131841542187;6249.500039996789;6285.779997700059;6328.905066424008;5692;5704;5706.315242170925;5764.74058667361;5771.942280577183;6507.796057729335;6525.042487253805;6528.771492997722;6621.966890165567;6639.601623902581;4826.227803551415;4827.179379505493;4827.420709514846;4881.731504767236;4887.364642596475;6934.549254298455;6992.1259842519685;7007.579545227302;7074.575525468437;7086.362227399507;4952.4057113146655;4955.281484184773;4965.875853103701;4965.925511117336;4980.252962055721;5020.322256495399;5029.270755740397;5052.595792336604;5070.949290507121;5090.752765572459;5679.075276232744;5706.771729130871;5735.340435849847;5757.798477053283;5804.506616937523;6613.735450582019;6619.205695316593;6651.33486651331;6675.1989761228;6741.52809303344;1657.9088150151615;1711.8801683882257;1716.3048965169125;1725.8360455756683;1743.2416898649128;1161.490062918203;1195.4739914437619;1207.9818802717966;1211.121936595961;1211.4487908001838;5862.537734151667;5888;5951.345352011275;5957.49361304294;5994.490468310242;6732.528722989344;6739.966300168468;6813.420859226922;6826.692798824065;6869.244383117853;5889.322727886348;5923.555733320019;5982.833347497283;6008.729607167688;6020.277566692025;3656.451532270181;3678.2643471305846;3684.2999830032745;3692.649198326155;3696.704263658901;6552.836179095465;6580.473562115019;6625.0724898514245;6642.568233064835;6666.566673166229;7090.184628767654;7090.822729431806;7106.680199391039;7130.215676275606;7201.13586369639;6566.80299591013;6593.868122637617;6620.8344791380605;6690.531662783558;6697.196336439463;6815.216250131299;6862.921438135843;6887.139107611549;6891.3797758202;6968.198657154289;6745.155261500898;6785.626790526371;6840.452763778786;6856.691448884712;6933.168019837652;6546.723388939111;6620.470362370903;6643;6657.833554161185;6670.7665231716655;4889.437714662859;4906.386701662293;4911.912437214099;4937.432195297443;4957.3059771632725;6788.9660551696925;6914.478814660766;6960.860782784214;6988.1083241732;6995.510314277952;6672.399484046459;6685.799426017227;6699.899501507579;6714.3957043866285;6718.395344419026;6630.46956243509;6688.4983626229205;6732.394084532416;6739.42714869222;6744.730210791513;6356.618602883842;6458.870822583616;6519.902201466887;6538.673066346759;6626.602403855784;6319.3364696706185;6585.835354116185;6600.405963463311;6613;6794.422474089758;6384.531557741652;6407.2631647359285;6412.711427985659;6467.641795222868;6540.50946179029 08:52:18 INFO - PID 3618 | |5;yui.html;882.2853488674178;892.870533772603;896.8923729152499;900.338874776038;915.4781774388599;16092.356305747771;16957.57606059849;16984.660306793856;17013.744793828086;19278.421647350566;18706.625867482642;18747.718784218214;18780.342688005923;18781.34265300715;18909;910.7586489580267;913.7258822353301;925.1534845616287;928.5682157796623;933.8879334479872;946.6639343033228;953.1755031897394;953.1802649721234;953.9570719317612;954.8472244440891;989.712983234861;992.2607657295289;992.9652462163825;993.3146129170885;993.741627176932;970.6699722094487;972.4019727867382;976.2434113561975;980.2256217588097;981.9934567068776;717.9964100179518;718.6299055986171;720.1538192623699;724.5181954000583;724.6594100772628;16895.57761055971;17064.914675426666;17435.65128697419;17697;17699;21188.788112118837;21193;21398.14407423701;21616.89191554048;21687.891560542255;17179.312827486887;17385.130743462778;17504.737428938577;17785.822141778546;17791.555211119696;3740.36413809653;3766.152615661454;3770.830312635938;3771.264603402338;3771.641694039062;129226;129363.35318323442;131126;134274;135114;16543.255553500123;16627.50117496477;17057.402990895258;17059.402920897708;17183.742243866353;29500.852495737814;30113.096607101823;30175.849120754257;30694;31096.689033109833;8160.4287699861625;8283.04744954338;8301.045379781282;8327.583620819056;8329.250367466957;4061.3501839705505;4126.504819421689;4182.874513764531;4306.633936115465;4343.674224433117;4880.170371036932;4947.90104197914;4952.702837829742;5026.4973502649445;5040.697558146523;920.2821798996794;948.9478078705599;962.8748262725941;963.0513943765385;964.2960638733764;681.1689738518999;683.1290105116017;688.789919074677;705.6753893209078;705.763569204312;11271.943640281746;11413.60052398162;11413.63036435633;11451.141164412536;11563.595274165364;658.4205898809113;668.7659319238228;672.3646154384157;673.4982438083659;687.3744892148121;5282.366116066097;5370.865728356823;6150.630962142286;6384.106225128486;6431.1317972073175;107153;107284.46357768161;109582;109593.45203274093;111594.44202779097;90971.54514227387;92342.07657923334;94819;94840.52579737196;100330;81835;84807.57596212103;84860;85215;86139;10891.346519208873;11313.151513636674;11543.134264930331;11701.590444334408;11731.120165987415;39219.41170882382;39644.008899776585;39864.60135398609;40578.79710601428;41106.38340424956;49966.25050624171;51033.23450148176;63812.68093659502;72456.63771681108;74269.6286518564;17859;18377.81622183761;18528.722069169242;18566.350177743716;18584;18778;18854.151563179417;18910.716339255196;18954.620907582048;19002.1449034791;18408.815911841248;18742.34401795985;18850.245990160787;18866.24535018584;18889.622207556047;18701.906490468005;19172.904135479792;19261.422157335302;19613.31353402624;19770.70343944813;16745.581360466087;16774.16129193511;16775.32898684088;18146.274149033892;18576.535586610444;11982.460789264329;12043.036557075588;13034.804477932648;13179.14335568185;13265.933670331586;12351.629451116482;12587.307698076695;13487.39306731219;13928.373223204777;13953.372098255806 08:52:18 INFO - PID 3618 | __end_tp_report 08:52:18 INFO - PID 3618 | __start_cc_report 08:52:18 INFO - PID 3618 | _x_x_mozilla_cycle_collect,364 08:52:18 INFO - PID 3618 | __end_cc_report 08:52:18 INFO - PID 3618 | __startTimestamp1503935538511__endTimestamp 08:52:18 INFO - PID 3618 | 08:52:18 INFO - PID 3618 | ------- Summary: start ------- 08:52:18 INFO - PID 3618 | Number of tests: 6 08:52:18 INFO - PID 3618 | 08:52:18 INFO - PID 3618 | [#0] dojo.html Cycles:165 Average:6297.34 Median:6556.41 stddev:722.13 (11.0%) stddev-sans-first:685.88 08:52:18 INFO - PID 3618 | Values: 3332.9 3337.2 3349.6 3361.2 3364.2 6302.0 6353.6 6402.1 6425.4 6509.3 6265.3 6338.3 6371.1 6373.4 6383.0 5777.5 5809.7 5838.9 5848.3 5895.7 5914.5 5945.2 5958.6 5974.1 5994.7 5745.7 5804.5 5866.9 5917.6 5963.6 5792.8 5801.5 5855.0 5881.5 5934.5 5794.6 5836.2 5864.5 5905.4 5914.1 6234.7 6235.8 6248.3 6260.5 6443.8 6742.3 6758.1 6761.9 6832.8 6844.1 6888.5 6908.8 6932.0 6941.4 6945.6 6309.0 6344.0 6405.6 6418.5 6429.0 6891.7 6902.9 6978.3 7096.4 7102.6 6667.9 6672.8 6673.4 6681.6 6701.8 6773.9 6788.4 6798.9 6883.7 6887.7 6809.9 6852.2 6854.2 6855.8 6869.7 6772.6 6773.8 6805.4 6889.3 6909.5 5306.5 5361.4 5378.3 5461.1 5465.6 4751.2 4771.3 4779.3 4812.8 4817.8 6001.3 6068.0 6070.5 6109.1 6114.7 6793.2 6851.6 6862.6 6936.2 6954.4 5830.4 5971.5 5972.3 6045.2 6068.8 5929.8 5951.9 5963.4 5967.8 5968.6 6568.7 6625.2 6628.4 6631.7 6651.6 6846.5 6853.1 6877.9 6948.5 6956.1 6432.2 6566.6 6571.2 6660.2 6675.3 6684.3 6699.4 6727.9 6762.9 6765.1 6817.1 6867.6 6875.1 6893.9 6912.1 6536.1 6581.0 6626.9 6633.9 6680.7 6556.4 6563.5 6594.6 6645.0 6681.3 6593.2 6598.7 6604.6 6610.1 6628.9 6635.9 6653.3 6734.9 6750.8 6781.7 6419.4 6463.0 6465.4 6547.7 6555.2 08:52:18 INFO - PID 3618 | 08:52:18 INFO - PID 3618 | [#1] ext.html Cycles:180 Average:38287.72 Median:36408.77 stddev:55005.52 (151.1%) stddev-sans-first:55087.97 08:52:18 INFO - PID 3618 | Values: 844.6 866.9 898.1 898.3 1133.3 30860.7 31438.4 31493.8 41512.2 42165.6 42072.8 42569.6 42626.9 42645.4 42655.8 7860.0 7950.1 8058.8 8063.8 8130.3 7888.7 7970.9 7989.0 8012.6 8021.8 7960.0 8014.6 8024.9 8043.9 8052.4 7956.2 8000.0 8001.6 8013.8 8028.6 7923.4 7990.4 7995.5 7999.0 7999.0 36382.5 36435.1 36577.5 36627.5 36632.5 3411.7 3435.0 3435.6 3441.0 3444.0 31181.5 31229.5 31284.2 31346.0 31382.4 5080.6 5185.9 5200.3 5222.6 5261.8 183822.0 184429.0 184986.0 185098.1 185732.0 44359.1 44435.3 44574.3 48342.8 48736.5 50866.2 50876.0 51062.7 51125.0 51165.0 16163.0 16176.4 16203.3 16434.8 16449.9 13430.2 13501.3 13514.9 13524.4 13543.6 13631.1 13682.2 13740.5 13894.9 13946.9 5664.6 5666.3 5666.9 5674.1 5687.2 984.4 985.5 998.5 999.2 1010.4 22690.7 22703.4 22765.2 22802.5 22904.3 1106.0 1108.2 1116.3 1120.4 1122.6 12182.4 12306.1 12315.5 12344.1 12366.8 233902.0 237256.0 237311.0 237405.0 237415.0 51121.0 51255.0 51451.0 52911.2 56079.0 1387.3 1417.2 1417.5 1420.1 1437.6 26519.7 26841.0 27508.7 27640.6 27849.9 38436.2 38810.8 38868.8 38908.0 39030.2 209613.0 213221.0 213313.0 213332.0 213710.0 36872.8 36917.0 36938.0 37090.1 37349.0 44482.3 45677.3 45687.3 45755.4 46119.3 33684.2 33746.8 33931.7 33975.8 34282.8 32954.0 33295.8 33555.8 33734.0 34258.5 37617.6 37626.8 37709.8 37716.6 38411.6 33316.3 33409.8 33743.0 33782.0 33813.3 23583.4 23701.2 23800.6 23903.5 23990.4 08:52:18 INFO - PID 3618 | 08:52:18 INFO - PID 3618 | [#2] jquery.html Cycles:180 Average:35724.69 Median:23758.58 stddev:85705.17 (360.7%) stddev-sans-first:85904.91 08:52:18 INFO - PID 3618 | Values: 555.8 563.6 565.3 565.9 585.3 21900.2 22337.0 22634.5 22662.5 22772.8 24172.8 24704.1 24705.5 24720.6 24742.3 6706.3 6811.6 6833.6 6836.7 6856.0 5914.4 6452.0 6452.1 6469.5 6482.2 6426.2 6435.7 6437.2 6442.1 6448.7 6415.0 6416.3 6418.9 6425.2 6428.7 5938.9 5961.5 5965.6 5969.8 5981.3 28046.3 28723.1 28992.1 29074.7 29188.7 2005.1 2040.0 2044.1 2044.5 2045.5 19232.5 19453.8 19753.5 19872.4 20127.0 2431.5 2436.0 2466.0 2471.3 2483.2 136134.0 139165.0 139428.0 140013.0 140022.0 14123.4 14635.7 14873.3 14912.8 14915.2 22915.8 22927.9 23017.4 23035.3 23074.0 9922.4 10016.1 10113.6 10178.6 10249.9 8242.0 8765.1 9476.4 9481.5 9501.7 8471.5 9110.5 9136.5 9225.8 9276.5 2601.3 2611.2 2612.4 2614.4 2748.8 918.9 950.1 961.9 971.8 980.6 18880.5 18948.4 18982.8 19092.8 19458.0 989.4 1011.6 1023.0 1029.6 1034.1 9535.3 9603.8 9738.5 9846.2 9881.5 483261.0 495844.0 509018.0 514469.0 536140.0 118860.0 125991.0 126358.0 129317.4 129598.0 1223.8 1235.5 1263.2 1264.3 1266.5 15915.8 16360.8 16361.5 16416.8 16445.7 55211.7 55247.2 55847.4 57315.7 57657.0 72850.6 74279.0 74397.0 75157.2 75201.6 11947.6 12383.9 14210.8 17622.6 20980.4 22888.0 23547.3 23601.8 23915.4 24323.1 18012.5 18607.6 18871.0 18893.1 18901.3 16751.3 17109.9 17414.1 17608.6 17738.9 18185.8 18338.2 19060.1 19219.6 19234.2 20383.4 20449.9 20755.1 20870.0 21128.8 18531.3 18561.5 18583.8 18785.0 18819.7 08:52:18 INFO - PID 3618 | 08:52:18 INFO - PID 3618 | [#3] mootools.html Cycles:180 Average:8930.47 Median:2680.14 stddev:6303.51 (235.2%) stddev-sans-first:6295.37 08:52:18 INFO - PID 3618 | Values: 1336.6 1381.7 1390.4 1391.8 1401.1 14474.6 14765.8 15062.9 15083.4 15110.2 14972.6 15101.8 15641.0 15690.5 15709.1 2543.7 2617.8 2668.5 2669.9 2671.6 2656.7 2684.3 2758.7 2840.6 2847.5 2796.4 2816.6 2833.8 2835.5 2844.1 2577.1 2732.2 2743.3 2756.3 2758.9 2676.0 2684.9 2685.7 2686.5 2689.7 14271.4 14586.6 14597.7 14605.7 14617.1 7528.8 7607.9 7784.5 7800.4 7801.0 5350.7 5479.9 5536.6 5556.2 5576.9 1891.7 1938.2 1940.5 1980.7 1993.8 24908.6 25128.5 25130.4 25132.4 25165.7 6519.3 6530.8 6542.5 6713.7 6789.7 16599.6 16630.6 16646.0 16653.9 16670.1 7641.3 7766.3 7782.9 7791.1 7793.6 6198.8 7174.6 7199.3 7438.7 7462.9 4762.4 5228.4 5348.9 5351.8 5357.9 1535.8 1561.9 1563.6 1579.9 1584.0 866.6 880.3 891.1 894.0 899.3 7846.8 8077.7 8108.8 8152.4 8189.4 914.6 931.5 953.7 983.7 989.5 4326.5 4381.9 4618.6 4943.3 5030.2 19738.4 19873.5 19897.2 20302.7 20690.7 19881.1 20091.7 20249.5 20271.5 20445.4 11054.3 11069.1 11255.1 11259.2 11261.5 7429.6 7690.2 7761.7 7805.3 7809.8 17462.5 17810.4 17997.9 18249.3 18337.4 18129.0 18197.1 18247.8 18284.9 18332.0 10531.2 10868.2 10868.6 10872.1 10879.2 11350.2 11946.6 12029.2 12040.9 13504.9 9982.9 9985.3 10014.8 10114.7 10186.7 10526.5 10600.4 10616.7 10776.0 10919.3 9137.7 9250.8 9255.2 9255.6 9256.0 6758.1 6789.2 6810.3 6819.8 6831.4 6637.2 6769.0 6786.3 6889.7 6937.5 08:52:18 INFO - PID 3618 | 08:52:18 INFO - PID 3618 | [#4] prototype.html Cycles:180 Average:5596.25 Median:6287.72 stddev:1597.52 (25.4%) stddev-sans-first:1555.49 08:52:18 INFO - PID 3618 | Values: 497.5 500.5 511.1 519.7 521.4 6470.3 6490.9 6508.7 6518.6 6521.2 6187.8 6271.9 6289.7 6328.1 6362.6 5077.8 5092.7 5110.8 5120.3 5145.8 5000.7 5013.2 5023.4 5042.7 5065.2 4818.1 4916.0 5004.1 5025.6 5035.2 4929.5 4987.8 4999.4 5000.7 5047.2 4446.5 4448.9 4470.8 4472.9 4495.4 6198.9 6201.1 6249.5 6285.8 6328.9 5692.0 5704.0 5706.3 5764.7 5771.9 6507.8 6525.0 6528.8 6622.0 6639.6 4826.2 4827.2 4827.4 4881.7 4887.4 6934.5 6992.1 7007.6 7074.6 7086.4 4952.4 4955.3 4965.9 4965.9 4980.3 5020.3 5029.3 5052.6 5070.9 5090.8 5679.1 5706.8 5735.3 5757.8 5804.5 6613.7 6619.2 6651.3 6675.2 6741.5 1657.9 1711.9 1716.3 1725.8 1743.2 1161.5 1195.5 1208.0 1211.1 1211.4 5862.5 5888.0 5951.3 5957.5 5994.5 6732.5 6740.0 6813.4 6826.7 6869.2 5889.3 5923.6 5982.8 6008.7 6020.3 3656.5 3678.3 3684.3 3692.6 3696.7 6552.8 6580.5 6625.1 6642.6 6666.6 7090.2 7090.8 7106.7 7130.2 7201.1 6566.8 6593.9 6620.8 6690.5 6697.2 6815.2 6862.9 6887.1 6891.4 6968.2 6745.2 6785.6 6840.5 6856.7 6933.2 6546.7 6620.5 6643.0 6657.8 6670.8 4889.4 4906.4 4911.9 4937.4 4957.3 6789.0 6914.5 6960.9 6988.1 6995.5 6672.4 6685.8 6699.9 6714.4 6718.4 6630.5 6688.5 6732.4 6739.4 6744.7 6356.6 6458.9 6519.9 6538.7 6626.6 6319.3 6585.8 6600.4 6613.0 6794.4 6384.5 6407.3 6412.7 6467.6 6540.5 08:52:18 INFO - PID 3618 | 08:52:18 INFO - PID 3618 | [#5] yui.html Cycles:180 Average:23292.63 Median:17418.53 stddev:32095.46 (184.3%) stddev-sans-first:32141.39 08:52:18 INFO - PID 3618 | Values: 882.3 892.9 896.9 900.3 915.5 16092.4 16957.6 16984.7 17013.7 19278.4 18706.6 18747.7 18780.3 18781.3 18909.0 910.8 913.7 925.2 928.6 933.9 946.7 953.2 953.2 954.0 954.8 989.7 992.3 993.0 993.3 993.7 970.7 972.4 976.2 980.2 982.0 718.0 718.6 720.2 724.5 724.7 16895.6 17064.9 17435.7 17697.0 17699.0 21188.8 21193.0 21398.1 21616.9 21687.9 17179.3 17385.1 17504.7 17785.8 17791.6 3740.4 3766.2 3770.8 3771.3 3771.6 129226.0 129363.4 131126.0 134274.0 135114.0 16543.3 16627.5 17057.4 17059.4 17183.7 29500.9 30113.1 30175.8 30694.0 31096.7 8160.4 8283.0 8301.0 8327.6 8329.3 4061.4 4126.5 4182.9 4306.6 4343.7 4880.2 4947.9 4952.7 5026.5 5040.7 920.3 948.9 962.9 963.1 964.3 681.2 683.1 688.8 705.7 705.8 11271.9 11413.6 11413.6 11451.1 11563.6 658.4 668.8 672.4 673.5 687.4 5282.4 5370.9 6150.6 6384.1 6431.1 107153.0 107284.5 109582.0 109593.5 111594.4 90971.5 92342.1 94819.0 94840.5 100330.0 81835.0 84807.6 84860.0 85215.0 86139.0 10891.3 11313.2 11543.1 11701.6 11731.1 39219.4 39644.0 39864.6 40578.8 41106.4 49966.3 51033.2 63812.7 72456.6 74269.6 17859.0 18377.8 18528.7 18566.4 18584.0 18778.0 18854.2 18910.7 18954.6 19002.1 18408.8 18742.3 18850.2 18866.2 18889.6 18701.9 19172.9 19261.4 19613.3 19770.7 16745.6 16774.2 16775.3 18146.3 18576.5 11982.5 12043.0 13034.8 13179.1 13265.9 12351.6 12587.3 13487.4 13928.4 13953.4 08:52:18 INFO - PID 3618 | -------- Summary: end -------- 08:52:18 INFO - PID 3618 | 08:52:19 INFO - TEST-INFO | 3618: exit 0 08:52:19 INFO - TEST-OK | dromaeo_css | took 1119990ms 08:52:19 INFO - TEST-START | kraken 08:52:19 INFO - Initialising browser for kraken test... 08:52:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50007/getInfo.html -profile /tmp/tmpmv_kzl/profile 08:52:19 INFO - TEST-INFO | started process 3978 (/builds/slave/test/build/application/firefox/firefox http://localhost:50007/getInfo.html) 08:52:25 INFO - TEST-INFO | 3978: exit 0 08:52:25 INFO - Browser initialized. 08:52:25 INFO - Running cycle 1/1 for kraken test... 08:52:25 INFO - Using env: {'DISPLAY': ':0', 08:52:25 INFO - 'HOME': '/home/cltbld', 08:52:25 INFO - 'JSGC_DISABLE_POISONING': '1', 08:52:25 INFO - 'LANG': 'en_US.UTF-8', 08:52:25 INFO - 'LANGUAGE': 'en_US:en', 08:52:25 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:52:25 INFO - 'LOGNAME': 'cltbld', 08:52:25 INFO - 'MAIL': '/var/mail/cltbld', 08:52:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:52:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:52:25 INFO - 'MOZ_CRASHREPORTER': '1', 08:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:25 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:52:25 INFO - 'MOZ_NO_REMOTE': '1', 08:52:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:52:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:25 INFO - 'NO_EM_RESTART': '1', 08:52:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:25 INFO - 'PWD': '/builds/slave/test', 08:52:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:52:25 INFO - 'RUST_BACKTRACE': 'full', 08:52:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:52:25 INFO - 'SHELL': '/bin/bash', 08:52:25 INFO - 'SHLVL': '1', 08:52:25 INFO - 'TERM': 'linux', 08:52:25 INFO - 'TMOUT': '86400', 08:52:25 INFO - 'USER': 'cltbld', 08:52:25 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174', 08:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:25 INFO - '_': '/tools/buildbot/bin/python'} 08:52:25 INFO - TEST-INFO | started process 4153 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmv_kzl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:52:26 INFO - PID 4153 | 08:52:26 INFO - PID 4153 | (/builds/slave/test/build/application/firefox/firefox:4203): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:52:26 INFO - PID 4153 | 08:52:37 INFO - PID 4153 | RSS: Main: 167653376 08:52:37 INFO - PID 4153 | 08:52:39 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50007/tests/kraken/driver.html?testName=audio-beat-detection) 08:52:40 INFO - PID 4153 | RSS: Main: 173613056 08:52:40 INFO - PID 4153 | 08:52:43 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50007/tests/kraken/driver.html?testName=audio-dft) 08:52:43 INFO - PID 4153 | RSS: Main: 177143808 08:52:43 INFO - PID 4153 | 08:52:46 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50007/tests/kraken/driver.html?testName=audio-fft) 08:52:47 INFO - PID 4153 | RSS: Main: 169402368 08:52:47 INFO - PID 4153 | 08:52:49 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50007/tests/kraken/driver.html?testName=audio-oscillator) 08:52:50 INFO - PID 4153 | RSS: Main: 170962944 08:52:50 INFO - PID 4153 | 08:52:52 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50007/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:52:52 INFO - PID 4153 | RSS: Main: 170708992 08:52:52 INFO - PID 4153 | 08:52:56 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50007/tests/kraken/driver.html?testName=imaging-darkroom) 08:52:56 INFO - PID 4153 | RSS: Main: 171638784 08:52:56 INFO - PID 4153 | 08:53:00 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50007/tests/kraken/driver.html?testName=imaging-desaturate) 08:53:00 INFO - PID 4153 | RSS: Main: 171507712 08:53:00 INFO - PID 4153 | 08:53:03 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50007/tests/kraken/driver.html?testName=json-parse-financial) 08:53:04 INFO - PID 4153 | RSS: Main: 172060672 08:53:04 INFO - PID 4153 | 08:53:05 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50007/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:53:06 INFO - PID 4153 | RSS: Main: 171900928 08:53:06 INFO - PID 4153 | 08:53:08 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50007/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:53:08 INFO - PID 4153 | RSS: Main: 171900928 08:53:08 INFO - PID 4153 | 08:53:10 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50007/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:53:11 INFO - PID 4153 | RSS: Main: 172142592 08:53:11 INFO - PID 4153 | 08:53:14 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:53:14 INFO - PID 4153 | RSS: Main: 171773952 08:53:14 INFO - PID 4153 | 08:53:17 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:53:18 INFO - PID 4153 | RSS: Main: 172511232 08:53:18 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | Cycle 1(1): loaded http://localhost:50007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50007/tests/kraken/driver.html?testName=ai-astar) 08:53:20 INFO - PID 4153 | RSS: Main: 173989888 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | __start_tp_report 08:53:20 INFO - PID 4153 | _x_x_mozilla_page_load 08:53:20 INFO - PID 4153 | _x_x_mozilla_page_load_details 08:53:20 INFO - PID 4153 | |i|pagename|runs| 08:53:20 INFO - PID 4153 | |0;ai-astar;98;108;100;107;110;110;107;98;107;108 08:53:20 INFO - PID 4153 | |1;audio-beat-detection;159;117;145;166;120;147;146;117;167;117 08:53:20 INFO - PID 4153 | |2;audio-dft;163;161;162;161;163;164;161;161;164;167 08:53:20 INFO - PID 4153 | |3;audio-fft;74;80;75;167;76;74;73;88;105;74 08:53:20 INFO - PID 4153 | |4;audio-oscillator;79;78;126;79;78;78;122;86;79;133 08:53:20 INFO - PID 4153 | |5;imaging-gaussian-blur;114;114;113;114;114;115;114;113;114;115 08:53:20 INFO - PID 4153 | |6;imaging-darkroom;148;149;149;148;149;149;149;151;148;149 08:53:20 INFO - PID 4153 | |7;imaging-desaturate;93;94;95;94;93;94;94;94;94;94 08:53:20 INFO - PID 4153 | |8;json-parse-financial;51;51;52;52;53;51;52;51;51;51 08:53:20 INFO - PID 4153 | |9;json-stringify-tinderbox;38;39;39;38;38;38;39;37;38;38 08:53:20 INFO - PID 4153 | |10;stanford-crypto-aes;75;74;75;76;75;75;74;74;75;75 08:53:20 INFO - PID 4153 | |11;stanford-crypto-ccm;121;138;123;119;120;131;120;119;118;155 08:53:20 INFO - PID 4153 | |12;stanford-crypto-pbkdf2;177;172;170;177;169;174;178;167;177;168 08:53:20 INFO - PID 4153 | |13;stanford-crypto-sha256-iterative;67;67;66;64;73;65;64;64;64;62 08:53:20 INFO - PID 4153 | __end_tp_report 08:53:20 INFO - PID 4153 | __start_cc_report 08:53:20 INFO - PID 4153 | _x_x_mozilla_cycle_collect,938 08:53:20 INFO - PID 4153 | __end_cc_report 08:53:20 INFO - PID 4153 | __startTimestamp1503935600390__endTimestamp 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | ------- Summary: start ------- 08:53:20 INFO - PID 4153 | Number of tests: 14 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#0] ai-astar Cycles:10 Average:105.30 Median:109.00 stddev:4.74 (4.3%) stddev-sans-first:4.23 08:53:20 INFO - PID 4153 | Values: 98.0 108.0 100.0 107.0 110.0 110.0 107.0 98.0 107.0 108.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:146.50 stddev:20.72 (14.1%) stddev-sans-first:20.81 08:53:20 INFO - PID 4153 | Values: 159.0 117.0 145.0 166.0 120.0 147.0 146.0 117.0 167.0 117.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.95 (1.2%) stddev-sans-first:2.06 08:53:20 INFO - PID 4153 | Values: 163.0 161.0 162.0 161.0 163.0 164.0 161.0 161.0 164.0 167.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#3] audio-fft Cycles:10 Average:88.60 Median:74.50 stddev:29.28 (39.3%) stddev-sans-first:30.58 08:53:20 INFO - PID 4153 | Values: 74.0 80.0 75.0 167.0 76.0 74.0 73.0 88.0 105.0 74.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:78.50 stddev:23.18 (29.5%) stddev-sans-first:23.96 08:53:20 INFO - PID 4153 | Values: 79.0 78.0 126.0 79.0 78.0 78.0 122.0 86.0 79.0 133.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 08:53:20 INFO - PID 4153 | Values: 114.0 114.0 113.0 114.0 114.0 115.0 114.0 113.0 114.0 115.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:0.88 (0.6%) stddev-sans-first:0.87 08:53:20 INFO - PID 4153 | Values: 148.0 149.0 149.0 148.0 149.0 149.0 149.0 151.0 148.0 149.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 08:53:20 INFO - PID 4153 | Values: 93.0 94.0 95.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:0.71 (1.4%) stddev-sans-first:0.73 08:53:20 INFO - PID 4153 | Values: 51.0 51.0 52.0 52.0 53.0 51.0 52.0 51.0 51.0 51.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.63 (1.7%) stddev-sans-first:0.67 08:53:20 INFO - PID 4153 | Values: 38.0 39.0 39.0 38.0 38.0 38.0 39.0 37.0 38.0 38.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:75.00 stddev:0.63 (0.8%) stddev-sans-first:0.67 08:53:20 INFO - PID 4153 | Values: 75.0 74.0 75.0 76.0 75.0 75.0 74.0 74.0 75.0 75.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:122.00 stddev:11.91 (9.8%) stddev-sans-first:12.47 08:53:20 INFO - PID 4153 | Values: 121.0 138.0 123.0 119.0 120.0 131.0 120.0 119.0 118.0 155.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:175.50 stddev:4.23 (2.4%) stddev-sans-first:4.22 08:53:20 INFO - PID 4153 | Values: 177.0 172.0 170.0 177.0 169.0 174.0 178.0 167.0 177.0 168.0 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.50 stddev:3.03 (4.6%) stddev-sans-first:3.17 08:53:20 INFO - PID 4153 | Values: 67.0 67.0 66.0 64.0 73.0 65.0 64.0 64.0 64.0 62.0 08:53:20 INFO - PID 4153 | -------- Summary: end -------- 08:53:20 INFO - PID 4153 | 08:53:20 INFO - PID 4153 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 08:53:21 INFO - TEST-INFO | 4153: exit 0 08:53:21 INFO - TEST-OK | kraken | took 61955ms 08:53:21 INFO - SUITE-END | took 1181s 08:53:21 INFO - Completed test suite (00:19:42) 08:53:21 INFO - javascript benchmark 08:53:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8534.709811801114, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3332.9000129996093, 3337.2324365395953, 3349.6315405305418, 3361.1933136047323, 3364.1757769346514, 6302.0, 6353.555251132417, 6402.135711678922, 6425.357464253578, 6509.349065093488, 6265.279492858323, 6338.271098823625, 6371.108044873721, 6373.394527519889, 6382.968085159568, 5777.45114214149, 5809.6804675742715, 5838.883222335551, 5848.2982042155, 5895.734691938873, 5914.497267732248, 5945.16767652529, 5958.553108516857, 5974.074018527113, 5994.700264986755, 5745.731649463432, 5804.535637149018, 5866.911996320058, 5917.644941303514, 5963.642181469104, 5792.797252096157, 5801.50687191585, 5855.0, 5881.47066763989, 5934.495567876693, 5794.5943783935145, 5836.171535114978, 5864.472197502245, 5905.43898329658, 5914.142449344864, 6234.719437625317, 6235.844103897393, 6248.250209974786, 6260.530460215502, 6443.806685799386, 6742.258351581322, 6758.05387245786, 6761.9323806761795, 6832.829179270509, 6844.1444819397575, 6888.517803753739, 6908.827279318007, 6931.965340173267, 6941.375276225113, 6945.617991010492, 6309.0, 6344.048392741126, 6405.647689377082, 6418.454431373339, 6429.03564465334, 6891.689873955684, 6902.861942761117, 6978.267281935324, 7096.396806271421, 7102.644867756591, 6667.899981500282, 6672.83317917056, 6673.432758215606, 6681.5656982296, 6701.832454188684, 6773.8983915241315, 6788.422984046312, 6798.898016529756, 6883.65581720922, 6887.724491020404, 6809.863802723918, 6852.177738671391, 6854.177498700188, 6855.760048398282, 6869.725210991504, 6772.5936443813525, 6773.830654233683, 6805.421539169127, 6889.345512176335, 6909.481788865855, 5306.52241298285, 5361.35663720356, 5378.327709036371, 5461.1262198048125, 5465.617406781566, 4751.168545504523, 4771.260454629538, 4779.330893674888, 4812.831550895702, 4817.759112044454, 6001.279846418458, 6068.0, 6070.484008859208, 6109.053096770008, 6114.6942652868065, 6793.150856142982, 6851.554648947803, 6862.553933994274, 6936.167659880846, 6954.4436445084275, 5830.387809279964, 5971.462568368867, 5972.313183983811, 6045.21412216409, 6068.787592434243, 5929.822105336847, 5951.9107213391835, 5963.4334738199805, 5967.761289548456, 5968.612040217372, 6568.737250510022, 6625.204975402982, 6628.436582890508, 6631.701573429207, 6651.567648102858, 6846.520743547904, 6853.074834897225, 6877.931220687929, 6948.478864085113, 6956.095707557985, 6432.195975503062, 6566.6388348641285, 6571.211454625475, 6660.167479065117, 6675.299093595103, 6684.298148694317, 6699.39705426514, 6727.865442691021, 6762.932370676231, 6765.1205343305055, 6817.147856517935, 6867.553609015398, 6875.07186529812, 6893.862122757617, 6912.101426814482, 6536.084948107364, 6581.0, 6626.900596491153, 6633.867322653617, 6680.732770689312, 6556.409923106943, 6563.474922006323, 6594.637294948824, 6644.966775166094, 6681.33186681328, 6593.241777195684, 6598.670066496752, 6604.636744979072, 6610.10763546915, 6628.867422651423, 6635.867282654223, 6653.268140504444, 6734.9224124141865, 6750.831229219309, 6781.728730850711, 6419.390157934989, 6463.030545418225, 6465.353464653497, 6547.672616369067, 6555.180602424697], "value": 6245.470357032708, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.560828369248, 866.9219770220681, 898.0615257056376, 898.2813749000798, 1133.257716195892, 30860.69139308606, 31438.371232575362, 31493.84253078737, 41512.16975660485, 42165.57834421655, 42072.78963605178, 42569.574304256945, 42626.8686565672, 42645.36031959515, 42655.78672106635, 7859.960700196491, 7950.1254861965135, 8058.758237252891, 8063.758087257363, 8130.308923741489, 7888.684452621888, 7970.8804367934235, 7989.0, 8012.63943122558, 8021.759347219593, 7959.960200198991, 8014.5591992440395, 8024.919750802475, 8043.879341809878, 8052.396070294752, 7956.204379562056, 7999.96000020002, 8001.599920003977, 8013.839723205532, 8028.558429286387, 7923.366130709529, 7990.360771138353, 7995.520268783834, 7998.960005199937, 7999.0, 36382.454263186075, 36435.089122771875, 36577.45133822995, 36627.4505882412, 36632.45051324232, 3411.727061835047, 3435.038189306998, 3435.57055368079, 3440.9827950860085, 3444.018454740412, 31181.532277015864, 31229.53155702666, 31284.217894552592, 31346.0, 31382.372352553048, 5080.568151707109, 5185.870353241162, 5200.323957885451, 5222.608304377187, 5261.815836445744, 183822.0, 184429.0, 184986.0, 185098.07450962794, 185732.0, 44359.112817743466, 44435.333469997975, 44574.33138502925, 48342.75828620834, 48736.51263487391, 50866.237006444935, 50876.0, 51062.74468627633, 51125.0, 51165.0, 16163.0, 16176.43382481631, 16203.270852811416, 16434.75347869783, 16449.917750411412, 13430.194188348534, 13501.257430841202, 13514.932425338007, 13524.391402386917, 13543.59369218925, 13631.113977591425, 13682.247476388695, 13740.450381984609, 13894.861051389558, 13946.930265348608, 5664.5751568631695, 5666.34836993751, 5666.891503659814, 5674.120511320752, 5687.203791469197, 984.3847595252967, 985.5318723606373, 998.5356809083811, 999.1507218863908, 1010.408910787183, 22690.659640105412, 22703.432414189778, 22765.203217887625, 22802.543949120925, 22904.312870613907, 1105.9612913548149, 1108.2076315434342, 1116.330201878866, 1120.3501968858207, 1122.6126986189752, 12182.390880455941, 12306.138570300172, 12315.50737970489, 12344.074194435274, 12366.75266494665, 233902.0, 237256.0, 237311.0, 237405.0, 237415.0, 51121.0, 51255.0, 51451.0, 52911.20633190428, 56079.0, 1387.257817067864, 1417.1638733147286, 1417.4755340524073, 1420.0982376190987, 1437.6118448018976, 26519.734802651725, 26841.0, 27508.724912750615, 27640.58539121955, 27849.860750696116, 38436.23127537489, 38810.80594597009, 38868.80565597267, 38908.0, 39030.2193956125, 209613.0, 213221.0, 213313.0, 213332.0, 213710.0, 36872.81563592165, 36917.0, 36938.0, 37090.07274818151, 37349.0, 44482.3327650079, 45677.314840278086, 45687.31469027901, 45755.398561050206, 46119.308210377545, 33684.15789605279, 33746.83126584352, 33931.66068339285, 33975.830120849234, 34282.82858585791, 32954.0, 33295.83352083224, 33555.83222083874, 33734.0, 34258.48612270768, 37617.62382376141, 37626.81186594049, 37709.811450943664, 37716.62283377241, 38411.6158838408, 33316.33367332591, 33409.83295083509, 33743.0, 33782.0, 33813.3237335247, 23583.410414739083, 23701.170459033852, 23800.642990355504, 23903.52192956166, 23990.400239993443], "value": 17444.670328587687, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.8082461550766, 563.6167406163808, 565.2510423688612, 565.9434056594339, 585.2947198625656, 21900.23349182777, 22337.0, 22634.54730905381, 22662.546749065008, 22772.77227227727, 24172.75827241727, 24704.135355262526, 24705.505889882235, 24720.62919056211, 24742.25773226806, 6706.295838936914, 6811.557248778839, 6833.589984600915, 6836.726530938757, 6856.0, 5914.378990206031, 6451.967685170373, 6452.096706461076, 6469.4500967417825, 6482.189726284215, 6426.196725409324, 6435.742570297182, 6437.195350581177, 6442.098106265126, 6448.742050317982, 6414.967925160391, 6416.262129855057, 6418.93581064188, 6425.164728585278, 6428.710708018127, 5938.910916336258, 5961.5230781537375, 5965.55258355625, 5969.791057312973, 5981.252343457068, 28046.298842528897, 28723.138305850858, 28992.13023609274, 29074.70925290741, 29188.708112918812, 2005.1377907499768, 2039.9592008159902, 2044.1108117968731, 2044.5399785048394, 2045.5193029638024, 19232.519187020298, 19453.80546194548, 19753.50616234591, 19872.403827885188, 20127.0, 2431.4924746657007, 2435.975640243593, 2465.9506809863883, 2471.270975062361, 2483.1681386735463, 136134.0, 139165.0, 139428.0, 140012.99999999898, 140022.0, 14123.43506259738, 14635.707285854223, 14873.33070011852, 14912.776308355167, 14915.179665118523, 22915.7708422917, 22927.88536057309, 23017.42456438569, 23035.308940731804, 23074.0, 9922.355046921926, 10016.098551130433, 10113.64602238918, 10178.592856285666, 10249.897501025043, 8242.0, 8765.07966663504, 9476.431414115175, 9481.4785186814, 9501.714948551555, 8471.491710497266, 9110.453372797654, 9136.543172841331, 9225.769355766159, 9276.489793061446, 2601.310652677042, 2611.242739605493, 2612.438325759933, 2614.437895852363, 2748.7938404619736, 918.86676431917, 950.12588418655, 961.8893827209961, 971.8493633486821, 980.5832521178471, 18880.527986800163, 18948.40972451205, 18982.810171898105, 19092.80907190938, 19458.027098645012, 989.366805244644, 1011.6256984915628, 1022.9693109206736, 1029.6344797596896, 1034.0590063042596, 9535.332526723063, 9603.807923841485, 9738.46438445878, 9846.163076138608, 9881.456519891475, 483261.0, 495844.0, 509018.0, 514469.0, 536140.0, 118860.0, 125991.0, 126358.0, 129317.35341323233, 129598.0, 1223.8286639870425, 1235.5490246060124, 1263.1663102352404, 1264.2667252993242, 1266.499732605634, 15915.761263581287, 16360.754588681417, 16361.50644300999, 16416.753748694016, 16445.67108657796, 55211.723941380034, 55247.17129243145, 55847.441525584225, 57315.71342143263, 57657.0, 72850.63574682093, 74279.0, 74397.0, 75157.24842751722, 75201.6239918797, 11947.581834635746, 12383.876161238271, 14210.78683819723, 17622.647547049244, 20980.370588882357, 22888.0, 23547.293581192593, 23601.763982359957, 23915.402114947265, 24323.148689795773, 18012.54968625795, 18607.627847443247, 18871.0, 18893.149808258935, 18901.338453154072, 16751.329946802478, 17109.914450427666, 17414.129293535017, 17608.55978600494, 17738.91130544339, 18185.818141818414, 18338.174782135102, 19060.1422935971, 19219.615607688047, 19234.23063077461, 20383.388498345073, 20449.89775051115, 20755.066022028743, 20870.0, 21128.78871211268, 18531.258749649864, 18561.53596160107, 18583.81416185875, 18785.0, 18819.71770423472], "value": 12051.915358438377, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.6190635668834, 1381.7443772902013, 1390.3743315508025, 1391.8468968413467, 1401.068289587424, 14474.638134046641, 14765.77851332231, 15062.92468537657, 15083.396664133448, 15110.168940708256, 14972.625684357896, 15101.791856651469, 15641.0, 15690.529284121494, 15709.057456552615, 2543.682039745032, 2617.842929424236, 2668.533006723825, 2669.866506674668, 2671.5591927331966, 2656.667916510436, 2684.3289177705574, 2758.7379198976064, 2840.5597132444504, 2847.530157524006, 2796.4127533218048, 2816.633837601109, 2833.7874659400522, 2835.4754370441433, 2844.075675405491, 2577.136659219164, 2732.2076597786618, 2743.2593199836133, 2756.2695885590333, 2758.9310267243377, 2675.959860602073, 2684.9463010739873, 2685.731426857318, 2686.489566982267, 2689.690685571165, 14271.429142834275, 14586.635334116625, 14597.70804583913, 14605.707885842223, 14617.122972621677, 7528.8117797054965, 7607.885881711723, 7784.455088143832, 7800.375969922449, 7800.960995195044, 5350.678959262418, 5479.890402191933, 5536.557075433996, 5556.222128901916, 5576.888462230732, 1891.7351570780102, 1938.185961896007, 1940.5051711813396, 1980.7425034745536, 1993.760748710164, 24908.626370604456, 25128.497430051295, 25130.371740706447, 25132.371690707696, 25165.74834251671, 6519.315471875385, 6530.8040758777315, 6542.476601871839, 6713.664316784141, 6789.725441976894, 16599.585010374838, 16630.58423539397, 16646.0, 16653.91673041627, 16670.083145427117, 7641.2740789624895, 7766.339861111869, 7782.883256751154, 7791.142974272825, 7793.610319484003, 6198.845028874314, 7174.569525828467, 7199.280071992864, 7438.66526006331, 7462.925370746331, 4762.357081793983, 5228.398734145546, 5348.893022139536, 5351.759170837322, 5357.892842143136, 1535.8080239970004, 1561.9375224990872, 1563.5543869996995, 1579.88940774147, 1583.9841601584067, 866.5667166416791, 880.2737741363401, 891.1445012787797, 893.9821203575892, 899.2536194958169, 7846.764597062097, 8077.717279895293, 8108.797280067927, 8152.388570857091, 8189.426740128253, 914.5930061122737, 931.5016466190556, 953.6948176583566, 983.680303901235, 989.5200827598609, 4326.502452217972, 4381.890452738643, 4618.584327410549, 4943.28322393251, 5030.245463180552, 19738.40784776459, 19873.503162420766, 19897.204111835363, 20302.69545956782, 20690.68963965542, 19881.105350258982, 20091.698624520643, 20249.49376265576, 20271.4932126698, 20445.386638400872, 11054.281471704475, 11069.114470842313, 11255.099592032619, 11259.155563332783, 11261.549538018387, 7429.591372474566, 7690.230976902265, 7761.68953241864, 7805.33654639362, 7809.843803123792, 17462.47612571625, 17810.376636817648, 17997.910010449865, 18249.270029198684, 18337.358192463198, 18129.0, 18197.090145492937, 18247.81752182461, 18284.908575457037, 18332.0, 10531.157507399543, 10868.18488613373, 10868.565257389932, 10872.075873550837, 10879.1840611956, 11350.205485615928, 11946.581869634521, 12029.218100823418, 12040.879591203975, 13504.86495135036, 9982.850257246293, 9985.251106166921, 10014.799704005733, 10114.747131321776, 10186.745331366776, 10526.473676316307, 10600.363978161335, 10616.734581635521, 10776.0, 10919.344839309666, 9137.680181193626, 9250.814983700422, 9255.213306868991, 9255.583498742708, 9256.0, 6758.087658166085, 6789.15135608049, 6810.31896810315, 6819.761308354184, 6831.351021652935, 6637.203535575569, 6769.018492318684, 6786.253512113565, 6889.724411023703, 6937.479689023445], "value": 6470.078242136781, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.5100111695875, 500.4897159647406, 511.108116336992, 519.6985748266006, 521.4290352064488, 6470.288268290485, 6490.870182596357, 6508.707108180131, 6518.641474718895, 6521.217453905538, 6187.783427580036, 6271.937280627192, 6289.654069026202, 6328.08242804793, 6362.586431881936, 5077.84766457005, 5092.745362731848, 5110.821121260757, 5120.308758317617, 5145.81989630363, 5000.749962501878, 5013.248012798092, 5023.372078490189, 5042.722650254234, 5065.189569668853, 4818.108649899762, 4915.975420122894, 5004.097623378432, 5025.597952163836, 5035.194368900977, 4929.507049295078, 4987.800487980477, 4999.350084488993, 5000.749962501878, 5047.2176812593925, 4446.509490050327, 4448.911021779546, 4470.8435204768, 4472.910541789179, 4495.370648109268, 6198.876022479525, 6201.131841542187, 6249.500039996789, 6285.779997700059, 6328.905066424008, 5692.0, 5704.0, 5706.315242170925, 5764.74058667361, 5771.942280577183, 6507.796057729335, 6525.042487253805, 6528.771492997722, 6621.966890165567, 6639.601623902581, 4826.227803551415, 4827.179379505493, 4827.420709514846, 4881.731504767236, 4887.364642596475, 6934.549254298455, 6992.1259842519685, 7007.579545227302, 7074.575525468437, 7086.362227399507, 4952.4057113146655, 4955.281484184773, 4965.875853103701, 4965.925511117336, 4980.252962055721, 5020.322256495399, 5029.270755740397, 5052.595792336604, 5070.949290507121, 5090.752765572459, 5679.075276232744, 5706.771729130871, 5735.340435849847, 5757.798477053283, 5804.506616937523, 6613.735450582019, 6619.205695316593, 6651.33486651331, 6675.1989761228, 6741.52809303344, 1657.9088150151615, 1711.8801683882257, 1716.3048965169125, 1725.8360455756683, 1743.2416898649128, 1161.490062918203, 1195.4739914437619, 1207.9818802717966, 1211.121936595961, 1211.4487908001838, 5862.537734151667, 5888.0, 5951.345352011275, 5957.49361304294, 5994.490468310242, 6732.528722989344, 6739.966300168468, 6813.420859226922, 6826.692798824065, 6869.244383117853, 5889.322727886348, 5923.555733320019, 5982.833347497283, 6008.729607167688, 6020.277566692025, 3656.451532270181, 3678.2643471305846, 3684.2999830032745, 3692.649198326155, 3696.704263658901, 6552.836179095465, 6580.473562115019, 6625.0724898514245, 6642.568233064835, 6666.566673166229, 7090.184628767654, 7090.822729431806, 7106.680199391039, 7130.215676275606, 7201.13586369639, 6566.80299591013, 6593.868122637617, 6620.8344791380605, 6690.531662783558, 6697.196336439463, 6815.216250131299, 6862.921438135843, 6887.139107611549, 6891.3797758202, 6968.198657154289, 6745.155261500898, 6785.626790526371, 6840.452763778786, 6856.691448884712, 6933.168019837652, 6546.723388939111, 6620.470362370903, 6643.0, 6657.833554161185, 6670.7665231716655, 4889.437714662859, 4906.386701662293, 4911.912437214099, 4937.432195297443, 4957.3059771632725, 6788.9660551696925, 6914.478814660766, 6960.860782784214, 6988.1083241732, 6995.510314277952, 6672.399484046459, 6685.799426017227, 6699.899501507579, 6714.3957043866285, 6718.395344419026, 6630.46956243509, 6688.4983626229205, 6732.394084532416, 6739.42714869222, 6744.730210791513, 6356.618602883842, 6458.870822583616, 6519.902201466887, 6538.673066346759, 6626.602403855784, 6319.3364696706185, 6585.835354116185, 6600.405963463311, 6613.0, 6794.422474089758, 6384.531557741652, 6407.2631647359285, 6412.711427985659, 6467.641795222868, 6540.50946179029], "value": 5119.247312155642, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [882.2853488674178, 892.870533772603, 896.8923729152499, 900.338874776038, 915.4781774388599, 16092.356305747771, 16957.57606059849, 16984.660306793856, 17013.744793828086, 19278.421647350566, 18706.625867482642, 18747.718784218214, 18780.342688005923, 18781.34265300715, 18909.0, 910.7586489580267, 913.7258822353301, 925.1534845616287, 928.5682157796623, 933.8879334479872, 946.6639343033228, 953.1755031897394, 953.1802649721234, 953.9570719317612, 954.8472244440891, 989.712983234861, 992.2607657295289, 992.9652462163825, 993.3146129170885, 993.741627176932, 970.6699722094487, 972.4019727867382, 976.2434113561975, 980.2256217588097, 981.9934567068776, 717.9964100179518, 718.6299055986171, 720.1538192623699, 724.5181954000583, 724.6594100772628, 16895.57761055971, 17064.914675426666, 17435.65128697419, 17697.0, 17699.0, 21188.788112118837, 21193.0, 21398.14407423701, 21616.89191554048, 21687.891560542255, 17179.312827486887, 17385.130743462778, 17504.737428938577, 17785.822141778546, 17791.555211119696, 3740.36413809653, 3766.152615661454, 3770.830312635938, 3771.264603402338, 3771.641694039062, 129226.0, 129363.35318323442, 131126.0, 134274.0, 135114.0, 16543.255553500123, 16627.50117496477, 17057.402990895258, 17059.402920897708, 17183.742243866353, 29500.852495737814, 30113.096607101823, 30175.849120754257, 30694.0, 31096.689033109833, 8160.4287699861625, 8283.04744954338, 8301.045379781282, 8327.583620819056, 8329.250367466957, 4061.3501839705505, 4126.504819421689, 4182.874513764531, 4306.633936115465, 4343.674224433117, 4880.170371036932, 4947.90104197914, 4952.702837829742, 5026.4973502649445, 5040.697558146523, 920.2821798996794, 948.9478078705599, 962.8748262725941, 963.0513943765385, 964.2960638733764, 681.1689738518999, 683.1290105116017, 688.789919074677, 705.6753893209078, 705.763569204312, 11271.943640281746, 11413.60052398162, 11413.63036435633, 11451.141164412536, 11563.595274165364, 658.4205898809113, 668.7659319238228, 672.3646154384157, 673.4982438083659, 687.3744892148121, 5282.366116066097, 5370.865728356823, 6150.630962142286, 6384.106225128486, 6431.1317972073175, 107153.0, 107284.46357768161, 109582.0, 109593.45203274093, 111594.44202779097, 90971.54514227387, 92342.07657923334, 94819.0, 94840.52579737196, 100330.0, 81835.0, 84807.57596212103, 84860.0, 85215.0, 86139.0, 10891.346519208873, 11313.151513636674, 11543.134264930331, 11701.590444334408, 11731.120165987415, 39219.41170882382, 39644.008899776585, 39864.60135398609, 40578.79710601428, 41106.38340424956, 49966.25050624171, 51033.23450148176, 63812.68093659502, 72456.63771681108, 74269.6286518564, 17859.0, 18377.81622183761, 18528.722069169242, 18566.350177743716, 18584.0, 18778.0, 18854.151563179417, 18910.716339255196, 18954.620907582048, 19002.1449034791, 18408.815911841248, 18742.34401795985, 18850.245990160787, 18866.24535018584, 18889.622207556047, 18701.906490468005, 19172.904135479792, 19261.422157335302, 19613.31353402624, 19770.70343944813, 16745.581360466087, 16774.16129193511, 16775.32898684088, 18146.274149033892, 18576.535586610444, 11982.460789264329, 12043.036557075588, 13034.804477932648, 13179.14335568185, 13265.933670331586, 12351.629451116482, 12587.307698076695, 13487.39306731219, 13928.373223204777, 13953.372098255806], "value": 8886.012913371998, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1476.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 100.0, 107.0, 110.0, 110.0, 107.0, 98.0, 107.0, 108.0], "value": 105.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 117.0, 145.0, 166.0, 120.0, 147.0, 146.0, 117.0, 167.0, 117.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 161.0, 163.0, 164.0, 161.0, 161.0, 164.0, 167.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 80.0, 75.0, 167.0, 76.0, 74.0, 73.0, 88.0, 105.0, 74.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 126.0, 79.0, 78.0, 78.0, 122.0, 86.0, 79.0, 133.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 114.0, 114.0, 115.0, 114.0, 113.0, 114.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 149.0, 148.0, 149.0, 149.0, 149.0, 151.0, 148.0, 149.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 52.0, 53.0, 51.0, 52.0, 51.0, 51.0, 51.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 39.0, 38.0, 38.0, 38.0, 39.0, 37.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 76.0, 75.0, 75.0, 74.0, 74.0, 75.0, 75.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 138.0, 123.0, 119.0, 120.0, 131.0, 120.0, 119.0, 118.0, 155.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 172.0, 170.0, 177.0, 169.0, 174.0, 178.0, 167.0, 177.0, 168.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 64.0, 73.0, 65.0, 64.0, 64.0, 64.0, 62.0], "value": 65.6, "unit": "score"}]}]} 08:53:21 INFO - Return code: 0 08:53:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:53:21 INFO - # TBPL SUCCESS # 08:53:21 INFO - Running post-action listener: _package_coverage_data 08:53:21 INFO - Running post-action listener: _resource_record_post_action 08:53:21 INFO - [mozharness: 2017-08-28 15:53:21.667707Z] Finished run-tests step (success) 08:53:21 INFO - Running post-run listener: _resource_record_post_run 08:53:21 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 5238784; Write bytes: 425353216; Read time: 3096; Write time: 934984 08:53:21 INFO - TinderboxPrint: CPU usage
13.1% 08:53:21 INFO - TinderboxPrint: I/O read bytes / time
5,238,784 / 3,096 08:53:21 INFO - TinderboxPrint: I/O write bytes / time
425,353,216 / 934,984 08:53:21 INFO - TinderboxPrint: CPU idle
8,309.6 (86.9%) 08:53:21 INFO - TinderboxPrint: CPU user
1,193.7 (12.5%) 08:53:21 INFO - TinderboxPrint: Swap in / out
0 / 0 08:53:21 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 105746432; Read time: 32; Write time: 255280 08:53:21 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:53:21 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 2863104; Write bytes: 319193088; Read time: 2268; Write time: 679704 08:53:22 INFO - Running post-run listener: _upload_blobber_files 08:53:22 INFO - Blob upload gear active. 08:53:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:53:22 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 08:53:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:53:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:53:22 INFO - (blobuploader) - INFO - Open directory for files ... 08:53:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:53:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:53:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:29 INFO - (blobuploader) - INFO - Done attempting. 08:53:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:53:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:53:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:30 INFO - (blobuploader) - INFO - Done attempting. 08:53:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:53:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:53:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:31 INFO - (blobuploader) - INFO - Done attempting. 08:53:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:53:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:53:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:32 INFO - (blobuploader) - INFO - Done attempting. 08:53:32 INFO - (blobuploader) - INFO - Iteration through files over. 08:53:32 INFO - Return code: 0 08:53:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:53:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:53:32 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef0a961240ae7d88f97538d099badb4f4bcc07b632bf27f0855c02a7759016331f9971e2345e66a5e43d17bc114508b05387b75cc46c67b4802c5e5c2ca77073", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbe3b3a1304f85aed0f41063b22d050516027d6004f9b8c77b5dd1ce4a2a5b1053b9b9c163cf1a62c30f50439ef48d2887518dd83800e45be2b366a7652af5d9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5adde3f53fce28c35ff3e520556c9f3c5b2c904e7aeeb927a39161fe3ece0910687ea7460d8b79a4d948a6bf7d2a2de77ca3ca0e2f54c11362f9755c642006ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bab6a31a4883e148a6135ebe24038ae6b122aefa4957b74098aae0a131f10645fba2711bd4eb8cc3f9aa8adb6407c7895f8cd9222f572b3d9cb308253158c365"} 08:53:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:53:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:53:32 INFO - Contents: 08:53:32 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef0a961240ae7d88f97538d099badb4f4bcc07b632bf27f0855c02a7759016331f9971e2345e66a5e43d17bc114508b05387b75cc46c67b4802c5e5c2ca77073", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbe3b3a1304f85aed0f41063b22d050516027d6004f9b8c77b5dd1ce4a2a5b1053b9b9c163cf1a62c30f50439ef48d2887518dd83800e45be2b366a7652af5d9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5adde3f53fce28c35ff3e520556c9f3c5b2c904e7aeeb927a39161fe3ece0910687ea7460d8b79a4d948a6bf7d2a2de77ca3ca0e2f54c11362f9755c642006ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bab6a31a4883e148a6135ebe24038ae6b122aefa4957b74098aae0a131f10645fba2711bd4eb8cc3f9aa8adb6407c7895f8cd9222f572b3d9cb308253158c365"} 08:53:32 INFO - Running post-run listener: copy_logs_to_upload_dir 08:53:32 INFO - Copying logs to upload dir... 08:53:32 INFO - mkdir: /builds/slave/test/build/upload/logs 08:53:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.621741 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-08-28 08:53:32.698041) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 08:53:32.705658) ========= 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=af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef0a961240ae7d88f97538d099badb4f4bcc07b632bf27f0855c02a7759016331f9971e2345e66a5e43d17bc114508b05387b75cc46c67b4802c5e5c2ca77073", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbe3b3a1304f85aed0f41063b22d050516027d6004f9b8c77b5dd1ce4a2a5b1053b9b9c163cf1a62c30f50439ef48d2887518dd83800e45be2b366a7652af5d9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5adde3f53fce28c35ff3e520556c9f3c5b2c904e7aeeb927a39161fe3ece0910687ea7460d8b79a4d948a6bf7d2a2de77ca3ca0e2f54c11362f9755c642006ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bab6a31a4883e148a6135ebe24038ae6b122aefa4957b74098aae0a131f10645fba2711bd4eb8cc3f9aa8adb6407c7895f8cd9222f572b3d9cb308253158c365"} build_url:https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011223 build_url: 'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef0a961240ae7d88f97538d099badb4f4bcc07b632bf27f0855c02a7759016331f9971e2345e66a5e43d17bc114508b05387b75cc46c67b4802c5e5c2ca77073", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbe3b3a1304f85aed0f41063b22d050516027d6004f9b8c77b5dd1ce4a2a5b1053b9b9c163cf1a62c30f50439ef48d2887518dd83800e45be2b366a7652af5d9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5adde3f53fce28c35ff3e520556c9f3c5b2c904e7aeeb927a39161fe3ece0910687ea7460d8b79a4d948a6bf7d2a2de77ca3ca0e2f54c11362f9755c642006ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bab6a31a4883e148a6135ebe24038ae6b122aefa4957b74098aae0a131f10645fba2711bd4eb8cc3f9aa8adb6407c7895f8cd9222f572b3d9cb308253158c365"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 08:53:32.743388) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:53:32.744066) ========= 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=af724c981e2a0f424dbabf99000001e7-1503934322.734626-240518174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:53:32.818155) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 08:53:32.821499) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 08:53:32.821817) ========= ========= Total master_lag: 0.28 =========