builder: mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s slave: talos-linux64-ix-043 starttime: 1504722315.2 results: success (0) revision: 93dd2e456c0ecca00fb4d28744e88078a77deaf7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 11:25:15.196607) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 11:25:15.197081) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 11:25:15.197377) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 11:25:15.215690) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:25:15.216135) ========= 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=d2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-06 11:25:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2017-09-06 11:25:15 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114776 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:25:15.348818) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:25:15.349144) ========= 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=d2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017486 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:25:15.398652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 18 secs) (at 2017-09-06 11:25:15.398996) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 93dd2e456c0ecca00fb4d28744e88078a77deaf7 --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 93dd2e456c0ecca00fb4d28744e88078a77deaf7 --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=d2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-06 11:25:15,434 truncating revision to first 12 chars 2017-09-06 11:25:15,435 Setting DEBUG logging. 2017-09-06 11:25:15,435 attempt 1/10 2017-09-06 11:25:15,435 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/93dd2e456c0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-06 11:25:16,008 attempt 1/10 2017-09-06 11:25:16,664 current task status: no status available at this point. state: PENDING 2017-09-06 11:25:16,664 sleeping for 10.00s (attempt 1/10) 2017-09-06 11:25:26,675 attempt 2/10 2017-09-06 11:25:27,208 current task status: no status available at this point. state: PENDING 2017-09-06 11:25:27,208 sleeping for 14.00s (attempt 2/10) 2017-09-06 11:25:41,222 attempt 3/10 2017-09-06 11:25:41,896 current task status: no status available at this point. state: PENDING 2017-09-06 11:25:41,896 sleeping for 20.00s (attempt 3/10) 2017-09-06 11:26:01,916 attempt 4/10 2017-09-06 11:26:02,706 current task status: no status available at this point. state: PENDING 2017-09-06 11:26:02,706 sleeping for 30.00s (attempt 4/10) 2017-09-06 11:26:32,736 attempt 5/10 2017-09-06 11:26:33,313 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-06 11:26:33,562 unpacking tar archive at: mozilla-central-93dd2e456c0e/testing/mozharness/ program finished with exit code 0 elapsedTime=78.508104 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 18 secs) (at 2017-09-06 11:26:33.933975) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 11:26:33.934378) ========= script_repo_revision: 93dd2e456c0ecca00fb4d28744e88078a77deaf7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 11:26:33.934751) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 11:26:33.935037) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 11:26:33.949100) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-06 11:26:33.949471) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:26:34 INFO - MultiFileLogger online at 20170906 11:26:34 in /builds/slave/test 11:26:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 11:26:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:26:34 INFO - {'append_to_log': False, 11:26:34 INFO - 'base_work_dir': '/builds/slave/test', 11:26:34 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:26:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:26:34 INFO - 'branch': 'Firefox-Non-PGO', 11:26:34 INFO - 'buildbot_json_path': 'buildprops.json', 11:26:34 INFO - 'code_coverage': False, 11:26:34 INFO - 'config_files': ('talos/linux_config.py',), 11:26:34 INFO - 'default_actions': ('clobber', 11:26:34 INFO - 'read-buildbot-config', 11:26:34 INFO - 'download-and-extract', 11:26:34 INFO - 'populate-webroot', 11:26:34 INFO - 'create-virtualenv', 11:26:34 INFO - 'install', 11:26:34 INFO - 'setup-mitmproxy', 11:26:34 INFO - 'run-tests'), 11:26:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:26:34 INFO - 'disable_ccov_upload': False, 11:26:34 INFO - 'disable_stylo': False, 11:26:34 INFO - 'download_minidump_stackwalk': True, 11:26:34 INFO - 'download_symbols': 'ondemand', 11:26:34 INFO - 'enable_stylo': False, 11:26:34 INFO - 'enable_webrender': False, 11:26:34 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:26:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:26:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:26:34 INFO - 'gecko_profile': False, 11:26:34 INFO - 'gecko_profile_interval': 0, 11:26:34 INFO - 'installer_path': 'installer.exe', 11:26:34 INFO - 'log_level': 'info', 11:26:34 INFO - 'log_name': 'talos', 11:26:34 INFO - 'log_to_console': True, 11:26:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:26:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:26:34 INFO - 'opt_config_files': (), 11:26:34 INFO - 'pip_index': False, 11:26:34 INFO - 'suite': 'dromaeojs-stylo-disabled-e10s', 11:26:34 INFO - 'system_bits': '32', 11:26:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:26:34 INFO - 'title': 'talos-linux64-ix-043', 11:26:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:26:34 INFO - 'use_talos_json': True, 11:26:34 INFO - 'verify': 'False', 11:26:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:26:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:26:34 INFO - 'work_dir': 'build'} 11:26:34 INFO - [mozharness: 2017-09-06 18:26:34.134259Z] Running clobber step. 11:26:34 INFO - Running pre-action listener: _resource_record_pre_action 11:26:34 INFO - Running main action method: clobber 11:26:34 INFO - rmtree: /builds/slave/test/build 11:26:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:26:35 INFO - Running post-action listener: _resource_record_post_action 11:26:35 INFO - [mozharness: 2017-09-06 18:26:35.673383Z] Finished clobber step (success) 11:26:35 INFO - [mozharness: 2017-09-06 18:26:35.673482Z] Running read-buildbot-config step. 11:26:35 INFO - Running pre-action listener: _resource_record_pre_action 11:26:35 INFO - Running main action method: read_buildbot_config 11:26:35 INFO - Using buildbot properties: 11:26:35 INFO - { 11:26:35 INFO - "project": "", 11:26:35 INFO - "product": "firefox", 11:26:35 INFO - "who": "ffxbld@noreply.mozilla.org", 11:26:35 INFO - "installer_path": "public/build/target.tar.bz2", 11:26:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s", 11:26:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:26:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-stylo-disabled-e10s", 11:26:35 INFO - "stage_platform": "linux64", 11:26:35 INFO - "basedir": "/builds/slave/test", 11:26:35 INFO - "buildnumber": 0, 11:26:35 INFO - "platform": "ubuntu64_hw", 11:26:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:26:35 INFO - "slavebuilddir": "test", 11:26:35 INFO - "taskId": "E33HI_EPT_et3uNVGisnWA", 11:26:35 INFO - "branch": "mozilla-central", 11:26:35 INFO - "script_repo_revision": "production", 11:26:35 INFO - "revision": "93dd2e456c0ecca00fb4d28744e88078a77deaf7", 11:26:35 INFO - "slavename": "talos-linux64-ix-043", 11:26:35 INFO - "repo_path": "mozilla-central" 11:26:35 INFO - } 11:26:35 INFO - Finding installer, test and symbols from parent task. 11:26:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E33HI_EPT_et3uNVGisnWA'}, attempt #1 11:26:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E33HI_EPT_et3uNVGisnWA'}, attempt #1 11:26:36 INFO - Task dependencies: CNiQiOuNT32DU3L464s6DA 11:26:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA'}, attempt #1 11:26:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 11:26:37 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.test_packages.json 11:26:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.crashreporter-symbols.zip 11:26:37 INFO - Running post-action listener: _resource_record_post_action 11:26:37 INFO - [mozharness: 2017-09-06 18:26:37.184811Z] Finished read-buildbot-config step (success) 11:26:37 INFO - [mozharness: 2017-09-06 18:26:37.185027Z] Running download-and-extract step. 11:26:37 INFO - Running pre-action listener: _resource_record_pre_action 11:26:37 INFO - Running main action method: download_and_extract 11:26:37 INFO - mkdir: /builds/slave/test/build/tests 11:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:37 INFO - trying https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.test_packages.json 11:26:37 INFO - Downloading https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:26:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:26:37 INFO - Downloaded 989 bytes. 11:26:37 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:26:37 INFO - Using the following test package requirements: 11:26:37 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:26:37 INFO - u'common': [u'target.common.tests.zip'], 11:26:37 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:26:37 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:26:37 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:26:37 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:26:37 INFO - u'mozbase': [u'target.common.tests.zip'], 11:26:37 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:26:37 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:26:37 INFO - u'web-platform': [u'target.common.tests.zip', 11:26:37 INFO - u'target.web-platform.tests.tar.gz'], 11:26:37 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:26:37 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:26:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip 11:26:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:26:37 INFO - Fetch https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip into memory 11:26:39 INFO - Content-Length response header: 55020142 11:26:39 INFO - Bytes received: 55020142 11:26:50 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:26:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip 11:26:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:26:50 INFO - Fetch https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip into memory 11:26:51 INFO - Content-Length response header: 55020142 11:26:51 INFO - Bytes received: 55020142 11:27:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.talos.tests.zip 11:27:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:27:03 INFO - Fetch https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.talos.tests.zip into memory 11:27:04 INFO - Content-Length response header: 14496665 11:27:04 INFO - Bytes received: 14496665 11:27: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')]} 11:27:04 INFO - trying https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 11:27:04 INFO - Downloading https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:27:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:27:06 INFO - Downloaded 62680086 bytes. 11:27:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 11:27:06 INFO - mkdir: /builds/slave/test/properties 11:27:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:27:06 INFO - Writing to file /builds/slave/test/properties/build_url 11:27:06 INFO - Contents: 11:27:06 INFO - build_url:https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 11:27:06 INFO - Running post-action listener: _resource_record_post_action 11:27:06 INFO - Running post-action listener: find_tests_for_verification 11:27:06 INFO - Running post-action listener: set_extra_try_arguments 11:27:06 INFO - [mozharness: 2017-09-06 18:27:06.475633Z] Finished download-and-extract step (success) 11:27:06 INFO - [mozharness: 2017-09-06 18:27:06.475838Z] Running populate-webroot step. 11:27:06 INFO - Running pre-action listener: _resource_record_pre_action 11:27:06 INFO - Running main action method: populate_webroot 11:27:06 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:27:06 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['tresize', 11:27:06 INFO - 'tcanvasmark']}, 11:27:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:27:06 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['dromaeo_css', 11:27:06 INFO - 'kraken']}, 11:27:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:27:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:27:06 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:27:06 INFO - 'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:27:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:27:06 INFO - 'tests': ['damp', 'tps']}, 11:27:06 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:27:06 INFO - 'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['damp', 'tps']}, 11:27:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:27:06 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['dromaeo_dom']}, 11:27:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:27:06 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['basic_compositor_video', 11:27:06 INFO - 'glvideo']}, 11:27:06 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:27:06 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:27:06 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:27:06 INFO - 'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['ts_paint_webext', 11:27:06 INFO - 'tp5o_webext']}, 11:27:06 INFO - 'other-e10s': {'tests': ['a11yr', 11:27:06 INFO - 'ts_paint', 11:27:06 INFO - 'tpaint', 11:27:06 INFO - 'sessionrestore', 11:27:06 INFO - 'sessionrestore_many_windows', 11:27:06 INFO - 'sessionrestore_no_auto_restore', 11:27:06 INFO - 'tabpaint']}, 11:27:06 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['a11yr', 11:27:06 INFO - 'ts_paint', 11:27:06 INFO - 'tpaint', 11:27:06 INFO - 'sessionrestore', 11:27:06 INFO - 'sessionrestore_many_windows', 11:27:06 INFO - 'sessionrestore_no_auto_restore', 11:27:06 INFO - 'tabpaint']}, 11:27:06 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:27:06 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:27:06 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['bloom_basic_singleton']}, 11:27:06 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['bloom_basic']}, 11:27:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:27:06 INFO - 'tsvgr_opacity', 11:27:06 INFO - 'tart', 11:27:06 INFO - 'tscrollx', 11:27:06 INFO - 'tsvg_static']}, 11:27:06 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['tsvgx', 11:27:06 INFO - 'tsvgr_opacity', 11:27:06 INFO - 'tart', 11:27:06 INFO - 'tscrollx', 11:27:06 INFO - 'tsvg_static']}, 11:27:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:27:06 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:27:06 INFO - 'talos_options': ['--disable-stylo'], 11:27:06 INFO - 'tests': ['tp5o']}, 11:27:06 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:27:06 INFO - 'talos_options': ['--mitmproxy', 11:27:06 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:27:06 INFO - '--firstNonBlankPaint'], 11:27:06 INFO - 'tests': ['tp6_google', 11:27:06 INFO - 'tp6_youtube', 11:27:06 INFO - 'tp6_amazon', 11:27:06 INFO - 'tp6_facebook']}, 11:27:06 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:27:06 INFO - 'talos_options': ['--disable-stylo', 11:27:06 INFO - '--mitmproxy', 11:27:06 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:27:06 INFO - '--firstNonBlankPaint'], 11:27:06 INFO - 'tests': ['tp6_google', 11:27:06 INFO - 'tp6_youtube', 11:27:06 INFO - 'tp6_amazon', 11:27:06 INFO - 'tp6_facebook']}, 11:27:06 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:27:06 INFO - 'talos_options': ['--stylo-threads=1', 11:27:06 INFO - '--mitmproxy', 11:27:06 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:27:06 INFO - '--firstNonBlankPaint'], 11:27:06 INFO - 'tests': ['tp6_google', 11:27:06 INFO - 'tp6_youtube', 11:27:06 INFO - 'tp6_amazon', 11:27:06 INFO - 'tp6_facebook']}, 11:27:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:27:06 INFO - 'talos_options': ['--xperf_path', 11:27:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:27:06 INFO - 'tests': ['tp5n']}, 11:27:06 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:27:06 INFO - 'talos_options': ['--disable-stylo', 11:27:06 INFO - '--xperf_path', 11:27:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:27:06 INFO - 'tests': ['tp5n']}}} 11:27:06 INFO - Running post-action listener: _resource_record_post_action 11:27:06 INFO - [mozharness: 2017-09-06 18:27:06.493951Z] Finished populate-webroot step (success) 11:27:06 INFO - [mozharness: 2017-09-06 18:27:06.494040Z] Running create-virtualenv step. 11:27:06 INFO - Running pre-action listener: _resource_record_pre_action 11:27:06 INFO - Running main action method: create_virtualenv 11:27:06 INFO - Creating virtualenv /builds/slave/test/build/venv 11:27:06 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:27:06 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:27:06 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:27:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:27:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:27:07 INFO - Installing distribute.............................................................................................................................................................................................done. 11:27:07 INFO - Installing pip...............done. 11:27:07 INFO - Return code: 0 11:27:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:27:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:27:07 INFO - Reading from file tmpfile_stdout 11:27:07 INFO - Output received: 11:27:07 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:27:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2976a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, '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': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:27:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:27:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:27:07 INFO - Using env: {'DISPLAY': ':0', 11:27:07 INFO - 'HOME': '/home/cltbld', 11:27:07 INFO - 'LANG': 'en_US.UTF-8', 11:27:07 INFO - 'LANGUAGE': 'en_US:en', 11:27:07 INFO - 'LOGNAME': 'cltbld', 11:27:07 INFO - 'MAIL': '/var/mail/cltbld', 11:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:07 INFO - 'MOZ_NO_REMOTE': '1', 11:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:07 INFO - 'NO_EM_RESTART': '1', 11:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:07 INFO - 'PWD': '/builds/slave/test', 11:27:07 INFO - 'SHELL': '/bin/bash', 11:27:07 INFO - 'SHLVL': '1', 11:27:07 INFO - 'TERM': 'linux', 11:27:07 INFO - 'TMOUT': '86400', 11:27:07 INFO - 'USER': 'cltbld', 11:27:07 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:07 INFO - '_': '/tools/buildbot/bin/python'} 11:27:08 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:27:08 INFO - Downloading/unpacking pip>=1.5 11:27:08 INFO - Running setup.py egg_info for package pip 11:27:08 INFO - warning: no files found matching 'pip/cacert.pem' 11:27:08 INFO - warning: no files found matching '*.html' under directory 'docs' 11:27:08 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:27:08 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:27:08 INFO - Installing collected packages: pip 11:27:08 INFO - Found existing installation: pip 1.1 11:27:08 INFO - Uninstalling pip: 11:27:08 INFO - Successfully uninstalled pip 11:27:08 INFO - Running setup.py install for pip 11:27:08 INFO - warning: no files found matching 'pip/cacert.pem' 11:27:08 INFO - warning: no files found matching '*.html' under directory 'docs' 11:27:08 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:27:08 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:27:08 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:27:08 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:27:08 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:27:08 INFO - Successfully installed pip 11:27:08 INFO - Cleaning up... 11:27:08 INFO - Return code: 0 11:27:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:08 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 0x260a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2976a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, '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': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:27:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:27:08 INFO - Using env: {'DISPLAY': ':0', 11:27:08 INFO - 'HOME': '/home/cltbld', 11:27:08 INFO - 'LANG': 'en_US.UTF-8', 11:27:08 INFO - 'LANGUAGE': 'en_US:en', 11:27:08 INFO - 'LOGNAME': 'cltbld', 11:27:08 INFO - 'MAIL': '/var/mail/cltbld', 11:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:08 INFO - 'MOZ_NO_REMOTE': '1', 11:27:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:08 INFO - 'NO_EM_RESTART': '1', 11:27:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:08 INFO - 'PWD': '/builds/slave/test', 11:27:08 INFO - 'SHELL': '/bin/bash', 11:27:08 INFO - 'SHLVL': '1', 11:27:08 INFO - 'TERM': 'linux', 11:27:08 INFO - 'TMOUT': '86400', 11:27:08 INFO - 'USER': 'cltbld', 11:27:08 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:08 INFO - '_': '/tools/buildbot/bin/python'} 11:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:08 INFO - Downloading/unpacking psutil>=3.1.1 11:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:27:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:27:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:27:10 INFO - Installing collected packages: psutil 11:27:10 INFO - Running setup.py install for psutil 11:27:10 INFO - building 'psutil._psutil_linux' extension 11:27:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:27:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:27:10 INFO - building 'psutil._psutil_posix' extension 11:27:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:27:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:27:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:27:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:27:10 INFO - Successfully installed psutil 11:27:10 INFO - Cleaning up... 11:27:10 INFO - Return code: 0 11:27:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:10 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 0x260a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2976a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, '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': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:27:10 INFO - Using env: {'DISPLAY': ':0', 11:27:10 INFO - 'HOME': '/home/cltbld', 11:27:10 INFO - 'LANG': 'en_US.UTF-8', 11:27:10 INFO - 'LANGUAGE': 'en_US:en', 11:27:10 INFO - 'LOGNAME': 'cltbld', 11:27:10 INFO - 'MAIL': '/var/mail/cltbld', 11:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:10 INFO - 'MOZ_NO_REMOTE': '1', 11:27:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:10 INFO - 'NO_EM_RESTART': '1', 11:27:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:10 INFO - 'PWD': '/builds/slave/test', 11:27:10 INFO - 'SHELL': '/bin/bash', 11:27:10 INFO - 'SHLVL': '1', 11:27:10 INFO - 'TERM': 'linux', 11:27:10 INFO - 'TMOUT': '86400', 11:27:10 INFO - 'USER': 'cltbld', 11:27:10 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:10 INFO - '_': '/tools/buildbot/bin/python'} 11:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:27: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 11:27: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 11:27: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 11:27: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 11:27:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:27:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:27:12 INFO - Installing collected packages: mozsystemmonitor 11:27:12 INFO - Running setup.py install for mozsystemmonitor 11:27:12 INFO - Successfully installed mozsystemmonitor 11:27:12 INFO - Cleaning up... 11:27:12 INFO - Return code: 0 11:27:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:12 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 0x260a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2976a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, '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': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:27:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:27:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:27:12 INFO - Using env: {'DISPLAY': ':0', 11:27:12 INFO - 'HOME': '/home/cltbld', 11:27:12 INFO - 'LANG': 'en_US.UTF-8', 11:27:12 INFO - 'LANGUAGE': 'en_US:en', 11:27:12 INFO - 'LOGNAME': 'cltbld', 11:27:12 INFO - 'MAIL': '/var/mail/cltbld', 11:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:12 INFO - 'MOZ_NO_REMOTE': '1', 11:27:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:12 INFO - 'NO_EM_RESTART': '1', 11:27:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:12 INFO - 'PWD': '/builds/slave/test', 11:27:12 INFO - 'SHELL': '/bin/bash', 11:27:12 INFO - 'SHLVL': '1', 11:27:12 INFO - 'TERM': 'linux', 11:27:12 INFO - 'TMOUT': '86400', 11:27:12 INFO - 'USER': 'cltbld', 11:27:12 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:12 INFO - '_': '/tools/buildbot/bin/python'} 11:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:12 INFO - Downloading/unpacking jsonschema==2.5.1 11:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:27:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:13 INFO - Downloading functools32-3.2.3-2.tar.gz 11:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:27:13 INFO - warning: no files found matching '*.txt' 11:27:13 INFO - no previously-included directories found matching 'build' 11:27:13 INFO - no previously-included directories found matching 'dist' 11:27:13 INFO - no previously-included directories found matching '.git*' 11:27:13 INFO - Installing collected packages: jsonschema, functools32 11:27:13 INFO - Running setup.py install for functools32 11:27:13 INFO - warning: no files found matching '*.txt' 11:27:13 INFO - no previously-included directories found matching 'build' 11:27:13 INFO - no previously-included directories found matching 'dist' 11:27:13 INFO - no previously-included directories found matching '.git*' 11:27:13 INFO - Successfully installed jsonschema functools32 11:27:13 INFO - Cleaning up... 11:27:13 INFO - Return code: 0 11:27:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:13 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 0x260a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2976a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, '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': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:27:13 INFO - Using env: {'DISPLAY': ':0', 11:27:13 INFO - 'HOME': '/home/cltbld', 11:27:13 INFO - 'LANG': 'en_US.UTF-8', 11:27:13 INFO - 'LANGUAGE': 'en_US:en', 11:27:13 INFO - 'LOGNAME': 'cltbld', 11:27:13 INFO - 'MAIL': '/var/mail/cltbld', 11:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:13 INFO - 'MOZ_NO_REMOTE': '1', 11:27:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:13 INFO - 'NO_EM_RESTART': '1', 11:27:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:13 INFO - 'PWD': '/builds/slave/test', 11:27:13 INFO - 'SHELL': '/bin/bash', 11:27:13 INFO - 'SHLVL': '1', 11:27:13 INFO - 'TERM': 'linux', 11:27:13 INFO - 'TMOUT': '86400', 11:27:13 INFO - 'USER': 'cltbld', 11:27:13 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:13 INFO - '_': '/tools/buildbot/bin/python'} 11:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:27:14 INFO - Cleaning up... 11:27:14 INFO - Return code: 0 11:27:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:14 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 0x260a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2976a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, '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': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:27:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:27:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:27:14 INFO - Using env: {'DISPLAY': ':0', 11:27:14 INFO - 'HOME': '/home/cltbld', 11:27:14 INFO - 'LANG': 'en_US.UTF-8', 11:27:14 INFO - 'LANGUAGE': 'en_US:en', 11:27:14 INFO - 'LOGNAME': 'cltbld', 11:27:14 INFO - 'MAIL': '/var/mail/cltbld', 11:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:14 INFO - 'MOZ_NO_REMOTE': '1', 11:27:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:14 INFO - 'NO_EM_RESTART': '1', 11:27:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:14 INFO - 'PWD': '/builds/slave/test', 11:27:14 INFO - 'SHELL': '/bin/bash', 11:27:14 INFO - 'SHLVL': '1', 11:27:14 INFO - 'TERM': 'linux', 11:27:14 INFO - 'TMOUT': '86400', 11:27:14 INFO - 'USER': 'cltbld', 11:27:14 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:14 INFO - '_': '/tools/buildbot/bin/python'} 11:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:14 INFO - Downloading/unpacking blobuploader==1.2.4 11:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:15 INFO - Downloading blobuploader-1.2.4.tar.gz 11:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:27:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:27:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:15 INFO - Downloading docopt-0.6.1.tar.gz 11:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:27:16 INFO - Installing collected packages: blobuploader, requests, docopt 11:27:16 INFO - Running setup.py install for blobuploader 11:27:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:27:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:27:16 INFO - Running setup.py install for requests 11:27:16 INFO - Running setup.py install for docopt 11:27:16 INFO - Successfully installed blobuploader requests docopt 11:27:16 INFO - Cleaning up... 11:27:16 INFO - Return code: 0 11:27:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:16 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 0x260a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2976a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, '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': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:27:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:27:16 INFO - Using env: {'DISPLAY': ':0', 11:27:16 INFO - 'HOME': '/home/cltbld', 11:27:16 INFO - 'LANG': 'en_US.UTF-8', 11:27:16 INFO - 'LANGUAGE': 'en_US:en', 11:27:16 INFO - 'LOGNAME': 'cltbld', 11:27:16 INFO - 'MAIL': '/var/mail/cltbld', 11:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:16 INFO - 'MOZ_NO_REMOTE': '1', 11:27:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:16 INFO - 'NO_EM_RESTART': '1', 11:27:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:16 INFO - 'PWD': '/builds/slave/test', 11:27:16 INFO - 'SHELL': '/bin/bash', 11:27:16 INFO - 'SHLVL': '1', 11:27:16 INFO - 'TERM': 'linux', 11:27:16 INFO - 'TMOUT': '86400', 11:27:16 INFO - 'USER': 'cltbld', 11:27:16 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:16 INFO - '_': '/tools/buildbot/bin/python'} 11:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:27:16 INFO - Running setup.py (path:/tmp/pip-8PjB3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:27:16 INFO - Running setup.py (path:/tmp/pip-2ByLfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:27:16 INFO - Running setup.py (path:/tmp/pip-5h30UO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:27:17 INFO - Running setup.py (path:/tmp/pip-emFM0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:27:17 INFO - Running setup.py (path:/tmp/pip-qofMTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:27:17 INFO - Running setup.py (path:/tmp/pip-ctbiH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:27:17 INFO - Running setup.py (path:/tmp/pip-8sa1u1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:27:17 INFO - Running setup.py (path:/tmp/pip-NtXNrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:27:17 INFO - Running setup.py (path:/tmp/pip-NJxTrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:27:17 INFO - Running setup.py (path:/tmp/pip-EAwsoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:27:17 INFO - Running setup.py (path:/tmp/pip-jDWOn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:27:17 INFO - Running setup.py (path:/tmp/pip-65AxeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:27:17 INFO - Running setup.py (path:/tmp/pip-fCO6Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:27:17 INFO - Running setup.py (path:/tmp/pip-TOvw_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:17 INFO - Running setup.py (path:/tmp/pip-TzeD6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:27:17 INFO - Running setup.py (path:/tmp/pip-pup3WL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:27:17 INFO - Running setup.py (path:/tmp/pip-62P_DQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:27:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:27:18 INFO - Running setup.py install for manifestparser 11:27:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:27:18 INFO - Running setup.py install for mozcrash 11:27:18 INFO - Running setup.py install for mozdebug 11:27:18 INFO - Running setup.py install for mozdevice 11:27:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:27:18 INFO - Running setup.py install for mozfile 11:27:18 INFO - Running setup.py install for mozhttpd 11:27:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:27:18 INFO - Running setup.py install for mozinfo 11:27:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:27:18 INFO - Running setup.py install for mozInstall 11:27:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:27:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:27:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:27:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:27:18 INFO - Running setup.py install for mozleak 11:27:18 INFO - Running setup.py install for mozlog 11:27:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:27:19 INFO - Running setup.py install for moznetwork 11:27:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:27:19 INFO - Running setup.py install for mozprocess 11:27:19 INFO - Running setup.py install for mozprofile 11:27:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:27:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:27:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:27:19 INFO - Running setup.py install for mozrunner 11:27:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:27:19 INFO - Running setup.py install for mozscreenshot 11:27:19 INFO - Running setup.py install for moztest 11:27:19 INFO - Running setup.py install for mozversion 11:27:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:27:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:27:19 INFO - Cleaning up... 11:27:19 INFO - Return code: 0 11:27:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:27:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:19 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 0x260a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2976a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, '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': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:27:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:27:19 INFO - Using env: {'DISPLAY': ':0', 11:27:19 INFO - 'HOME': '/home/cltbld', 11:27:19 INFO - 'LANG': 'en_US.UTF-8', 11:27:19 INFO - 'LANGUAGE': 'en_US:en', 11:27:19 INFO - 'LOGNAME': 'cltbld', 11:27:19 INFO - 'MAIL': '/var/mail/cltbld', 11:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:19 INFO - 'MOZ_NO_REMOTE': '1', 11:27:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:19 INFO - 'NO_EM_RESTART': '1', 11:27:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:19 INFO - 'PWD': '/builds/slave/test', 11:27:19 INFO - 'SHELL': '/bin/bash', 11:27:19 INFO - 'SHLVL': '1', 11:27:19 INFO - 'TERM': 'linux', 11:27:19 INFO - 'TMOUT': '86400', 11:27:19 INFO - 'USER': 'cltbld', 11:27:19 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:19 INFO - '_': '/tools/buildbot/bin/python'} 11:27:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:27:20 INFO - Running setup.py (path:/tmp/pip-bCaeNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:27:20 INFO - Running setup.py (path:/tmp/pip-B6kM3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:27:20 INFO - Running setup.py (path:/tmp/pip-0cHsqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:27:20 INFO - Running setup.py (path:/tmp/pip-EAo4rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:27:20 INFO - Running setup.py (path:/tmp/pip-HH5T_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:27:20 INFO - Running setup.py (path:/tmp/pip-UEkLbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:27:20 INFO - Running setup.py (path:/tmp/pip-zgKjBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:27:20 INFO - Running setup.py (path:/tmp/pip-QPhGz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:27:20 INFO - Running setup.py (path:/tmp/pip-Hrkz0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:27:20 INFO - Running setup.py (path:/tmp/pip-9YdB0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:27:20 INFO - Running setup.py (path:/tmp/pip-gXgV4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:27:20 INFO - Running setup.py (path:/tmp/pip-AbBLRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:27:20 INFO - Running setup.py (path:/tmp/pip-f7em1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:27:20 INFO - Running setup.py (path:/tmp/pip-aOe0zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:21 INFO - Running setup.py (path:/tmp/pip-gh_2JC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:27:21 INFO - Running setup.py (path:/tmp/pip-7HewMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:27:21 INFO - Running setup.py (path:/tmp/pip-FC2X2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:27:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:22 INFO - Downloading blessings-1.6.tar.gz 11:27:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:27:22 INFO - Installing collected packages: blessings 11:27:22 INFO - Running setup.py install for blessings 11:27:22 INFO - Successfully installed blessings 11:27:22 INFO - Cleaning up... 11:27:22 INFO - Return code: 0 11:27:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:27:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:27:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:27:22 INFO - Reading from file tmpfile_stdout 11:27:22 INFO - Current package versions: 11:27:22 INFO - argparse == 1.2.1 11:27:22 INFO - blessings == 1.6 11:27:22 INFO - blobuploader == 1.2.4 11:27:22 INFO - docopt == 0.6.1 11:27:22 INFO - functools32 == 3.2.3-2 11:27:22 INFO - jsonschema == 2.5.1 11:27:22 INFO - manifestparser == 1.1 11:27:22 INFO - mozInstall == 1.13 11:27:22 INFO - mozcrash == 1.0 11:27:22 INFO - mozdebug == 0.1 11:27:22 INFO - mozdevice == 0.51 11:27:22 INFO - mozfile == 1.2 11:27:22 INFO - mozhttpd == 0.7 11:27:22 INFO - mozinfo == 0.10 11:27:22 INFO - mozleak == 0.1 11:27:22 INFO - mozlog == 3.5 11:27:22 INFO - moznetwork == 0.27 11:27:22 INFO - mozprocess == 0.25 11:27:22 INFO - mozprofile == 0.28 11:27:22 INFO - mozrunner == 6.13 11:27:22 INFO - mozscreenshot == 0.1 11:27:22 INFO - mozsystemmonitor == 0.3 11:27:22 INFO - moztest == 0.8 11:27:22 INFO - mozversion == 1.4 11:27:22 INFO - psutil == 3.1.1 11:27:22 INFO - requests == 1.2.3 11:27:22 INFO - wsgiref == 0.1.2 11:27:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:22 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 0x260a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2976a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, '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': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:27:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:27:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:27:22 INFO - Using env: {'DISPLAY': ':0', 11:27:22 INFO - 'HOME': '/home/cltbld', 11:27:22 INFO - 'LANG': 'en_US.UTF-8', 11:27:22 INFO - 'LANGUAGE': 'en_US:en', 11:27:22 INFO - 'LOGNAME': 'cltbld', 11:27:22 INFO - 'MAIL': '/var/mail/cltbld', 11:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:22 INFO - 'MOZ_NO_REMOTE': '1', 11:27:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:22 INFO - 'NO_EM_RESTART': '1', 11:27:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:22 INFO - 'PWD': '/builds/slave/test', 11:27:22 INFO - 'SHELL': '/bin/bash', 11:27:22 INFO - 'SHLVL': '1', 11:27:22 INFO - 'TERM': 'linux', 11:27:22 INFO - 'TMOUT': '86400', 11:27:22 INFO - 'USER': 'cltbld', 11:27:22 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:22 INFO - '_': '/tools/buildbot/bin/python'} 11:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:27:22 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:27:24 INFO - Installing collected packages: simplejson 11:27:24 INFO - Running setup.py install for simplejson 11:27:24 INFO - building 'simplejson._speedups' extension 11:27:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:27:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 11:27:25 INFO - Successfully installed simplejson 11:27:25 INFO - Cleaning up... 11:27:25 INFO - Return code: 0 11:27:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:25 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 0x260a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2976a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, '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': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:27:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:27:25 INFO - Using env: {'DISPLAY': ':0', 11:27:25 INFO - 'HOME': '/home/cltbld', 11:27:25 INFO - 'LANG': 'en_US.UTF-8', 11:27:25 INFO - 'LANGUAGE': 'en_US:en', 11:27:25 INFO - 'LOGNAME': 'cltbld', 11:27:25 INFO - 'MAIL': '/var/mail/cltbld', 11:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:25 INFO - 'MOZ_NO_REMOTE': '1', 11:27:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:25 INFO - 'NO_EM_RESTART': '1', 11:27:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:25 INFO - 'PWD': '/builds/slave/test', 11:27:25 INFO - 'SHELL': '/bin/bash', 11:27:25 INFO - 'SHLVL': '1', 11:27:25 INFO - 'TERM': 'linux', 11:27:25 INFO - 'TMOUT': '86400', 11:27:25 INFO - 'USER': 'cltbld', 11:27:25 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:25 INFO - '_': '/tools/buildbot/bin/python'} 11:27:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:27:25 INFO - Cleaning up... 11:27:25 INFO - Return code: 0 11:27:25 INFO - Running post-action listener: _resource_record_post_action 11:27:25 INFO - Running post-action listener: _start_resource_monitoring 11:27:25 INFO - Starting resource monitoring. 11:27:25 INFO - [mozharness: 2017-09-06 18:27:25.490991Z] Finished create-virtualenv step (success) 11:27:25 INFO - [mozharness: 2017-09-06 18:27:25.491463Z] Running install step. 11:27:25 INFO - Running pre-action listener: _resource_record_pre_action 11:27:25 INFO - Running main action method: install 11:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:27:25 INFO - Reading from file tmpfile_stdout 11:27:25 INFO - Detecting whether we're running mozinstall >=1.0... 11:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:27:25 INFO - Reading from file tmpfile_stdout 11:27:25 INFO - Output received: 11:27:25 INFO - Usage: mozinstall [options] installer 11:27:25 INFO - Options: 11:27:25 INFO - -h, --help show this help message and exit 11:27:25 INFO - -d DEST, --destination=DEST 11:27:25 INFO - Directory to install application into. [default: 11:27:25 INFO - "/builds/slave/test"] 11:27:25 INFO - --app=APP Application being installed. [default: firefox] 11:27:25 INFO - mkdir: /builds/slave/test/build/application 11:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:27:42 INFO - Reading from file tmpfile_stdout 11:27:42 INFO - Output received: 11:27:42 INFO - /builds/slave/test/build/application/firefox/firefox 11:27:42 INFO - Running post-action listener: _resource_record_post_action 11:27:42 INFO - [mozharness: 2017-09-06 18:27:42.388560Z] Finished install step (success) 11:27:42 INFO - [mozharness: 2017-09-06 18:27:42.388824Z] Running setup-mitmproxy step. 11:27:42 INFO - Running pre-action listener: _resource_record_pre_action 11:27:42 INFO - Running main action method: setup_mitmproxy 11:27:42 INFO - Skipping: mitmproxy is not required 11:27:42 INFO - Running post-action listener: _resource_record_post_action 11:27:42 INFO - [mozharness: 2017-09-06 18:27:42.390014Z] Finished setup-mitmproxy step (success) 11:27:42 INFO - [mozharness: 2017-09-06 18:27:42.390232Z] Running run-tests step. 11:27:42 INFO - Running pre-action listener: _resource_record_pre_action 11:27:42 INFO - Running pre-action listener: _set_gcov_prefix 11:27:42 INFO - Running main action method: run_tests 11:27:42 WARNING - Try message not found. 11:27:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:27:42 INFO - Python 2.7.3 11:27:42 INFO - Return code: 0 11:27:42 INFO - grabbing minidump binary from tooltool 11:27:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:42 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 0x297a090>, '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 0x297ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2974f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:27:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:27:42 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:27:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:27:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:27:42 INFO - Return code: 0 11:27:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:27:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:27:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:27:42 INFO - ENV: RUST_BACKTRACE is now full 11:27:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:27:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:27:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build 11:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-043 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log 11:27:42 INFO - Using env: {'DISPLAY': ':0', 11:27:42 INFO - 'HOME': '/home/cltbld', 11:27:42 INFO - 'LANG': 'en_US.UTF-8', 11:27:42 INFO - 'LANGUAGE': 'en_US:en', 11:27:42 INFO - 'LOGNAME': 'cltbld', 11:27:42 INFO - 'MAIL': '/var/mail/cltbld', 11:27:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:42 INFO - 'MOZ_NO_REMOTE': '1', 11:27:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:42 INFO - 'NO_EM_RESTART': '1', 11:27:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:42 INFO - 'PWD': '/builds/slave/test', 11:27:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:42 INFO - 'RUST_BACKTRACE': 'full', 11:27:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:27:42 INFO - 'SHELL': '/bin/bash', 11:27:42 INFO - 'SHLVL': '1', 11:27:42 INFO - 'TERM': 'linux', 11:27:42 INFO - 'TMOUT': '86400', 11:27:42 INFO - 'USER': 'cltbld', 11:27:42 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:42 INFO - '_': '/tools/buildbot/bin/python'} 11:27:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] with output_timeout 3600 11:27:42 INFO - mozversion application_buildid: 20170906173802 11:27:42 INFO - mozversion application_changeset: 93dd2e456c0ecca00fb4d28744e88078a77deaf7 11:27:42 INFO - mozversion application_display_name: Nightly 11:27:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:27:42 INFO - mozversion application_name: Firefox 11:27:42 INFO - mozversion application_remotingname: firefox 11:27:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:27:42 INFO - mozversion application_vendor: Mozilla 11:27:42 INFO - mozversion application_version: 57.0a1 11:27:42 INFO - mozversion platform_buildid: 20170906173802 11:27:42 INFO - mozversion platform_changeset: 93dd2e456c0ecca00fb4d28744e88078a77deaf7 11:27:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:27:42 INFO - mozversion platform_version: 57.0a1 11:27:42 INFO - using testdate: 1504722462 11:27:42 INFO - actual date: 1504722462 11:27:42 INFO - starting webserver on 'localhost:34889' 11:27:42 INFO - SUITE-START | Running 2 tests 11:27:42 INFO - TEST-START | dromaeo_css 11:27:42 INFO - Initialising browser for dromaeo_css test... 11:27:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34889/getInfo.html -profile /tmp/tmp5hUOOK/profile 11:27:42 INFO - TEST-INFO | started process 13761 (/builds/slave/test/build/application/firefox/firefox http://localhost:34889/getInfo.html) 11:27:48 INFO - TEST-INFO | 13761: exit 0 11:27:48 INFO - Browser initialized. 11:27:48 INFO - Running cycle 1/1 for dromaeo_css test... 11:27:48 INFO - Using env: {'DISPLAY': ':0', 11:27:48 INFO - 'HOME': '/home/cltbld', 11:27:48 INFO - 'JSGC_DISABLE_POISONING': '1', 11:27:48 INFO - 'LANG': 'en_US.UTF-8', 11:27:48 INFO - 'LANGUAGE': 'en_US:en', 11:27:48 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:27:48 INFO - 'LOGNAME': 'cltbld', 11:27:48 INFO - 'MAIL': '/var/mail/cltbld', 11:27:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:48 INFO - 'MOZ_CRASHREPORTER': '1', 11:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:48 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:27:48 INFO - 'MOZ_NO_REMOTE': '1', 11:27:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:48 INFO - 'NO_EM_RESTART': '1', 11:27:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:48 INFO - 'PWD': '/builds/slave/test', 11:27:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:48 INFO - 'RUST_BACKTRACE': 'full', 11:27:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:27:48 INFO - 'SHELL': '/bin/bash', 11:27:48 INFO - 'SHLVL': '1', 11:27:48 INFO - 'STYLO_FORCE_DISABLED': '1', 11:27:48 INFO - 'TERM': 'linux', 11:27:48 INFO - 'TMOUT': '86400', 11:27:48 INFO - 'USER': 'cltbld', 11:27:48 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:48 INFO - '_': '/tools/buildbot/bin/python'} 11:27:48 INFO - TEST-INFO | started process 13955 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5hUOOK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:27:49 INFO - PID 13955 | 11:27:49 INFO - PID 13955 | (/builds/slave/test/build/application/firefox/firefox:14008): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:27:49 INFO - PID 13955 | 11:27:59 INFO - PID 13955 | RSS: Main: 169848832 11:27:59 INFO - PID 13955 | 11:30:56 INFO - PID 13955 | Cycle 1(1): loaded http://localhost:34889/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34889/tests/dromaeo/cssquery-ext.html) 11:30:58 INFO - PID 13955 | RSS: Main: 173129728 11:30:58 INFO - PID 13955 | 11:33:59 INFO - PID 13955 | Cycle 1(1): loaded http://localhost:34889/tests/dromaeo/cssquery-ext.html (next: http://localhost:34889/tests/dromaeo/cssquery-jquery.html) 11:34:00 INFO - PID 13955 | RSS: Main: 169353216 11:34:00 INFO - PID 13955 | 11:37:02 INFO - PID 13955 | Cycle 1(1): loaded http://localhost:34889/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34889/tests/dromaeo/cssquery-mootools.html) 11:37:02 INFO - PID 13955 | RSS: Main: 171642880 11:37:02 INFO - PID 13955 | 11:40:04 INFO - PID 13955 | Cycle 1(1): loaded http://localhost:34889/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34889/tests/dromaeo/cssquery-prototype.html) 11:40:04 INFO - PID 13955 | RSS: Main: 170545152 11:40:04 INFO - PID 13955 | 11:43:08 INFO - PID 13955 | Cycle 1(1): loaded http://localhost:34889/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34889/tests/dromaeo/cssquery-yui.html) 11:43:08 INFO - PID 13955 | RSS: Main: 171266048 11:43:08 INFO - PID 13955 | 11:46:10 INFO - PID 13955 | Cycle 1(1): loaded http://localhost:34889/tests/dromaeo/cssquery-yui.html (next: http://localhost:34889/tests/dromaeo/cssquery-dojo.html) 11:46:10 INFO - PID 13955 | RSS: Main: 174223360 11:46:10 INFO - PID 13955 | 11:46:10 INFO - PID 13955 | __start_tp_report 11:46:10 INFO - PID 13955 | _x_x_mozilla_page_load 11:46:10 INFO - PID 13955 | _x_x_mozilla_page_load_details 11:46:10 INFO - PID 13955 | |i|pagename|runs| 11:46:10 INFO - PID 13955 | |0;dojo.html;3308.9338213235737;3310.768246222764;3327.1515763480315;3341.9164520886975;3343.063942096214;6409.302279118321;6465.062565927938;6484.578502397341;6513.185851768529;6536.705848236829;6366.458850997659;6448.613083214998;6452.870942581146;6468.417842394184;6495.480361571074;5915.88168236635;5928.703564821763;5955.255593050869;5985.760569577234;5997.910031349533;5959.731812068468;5974.044152935531;5986.940130598682;6019.879602407949;6062.363451837559;5978.40215978403;6042.516598672096;6051.243594550681;6060.060690592965;6095.878082438348;5888.705564721789;5947.4944629706315;5959.0763431668165;5963.522918166536;6058.273007239115;5740.052891272935;5807.332156801979;5817.505512031483;5849.7660093596205;5926.199963004982;6399.328070552571;6464.25661048976;6487.481001519867;6497.967510162466;6511.674416279215;6894.207166175904;6905.240423553405;6931.168259808805;7005.754798582077;7026.894596581055;6742.460603151736;6834.897476537856;6838.042674025641;6841.521093523455;6846.691898864513;6344.619322840645;6348.492120630339;6348.968255158741;6359.20509936258;6400.583962042452;7108.822279443055;7109.111361079865;7197.992281080653;7210;7213.1704853942465;6786.558873673195;6830.556013859084;6847.589144651337;6850.588964662136;6854.177498700188;6906.965465172642;6935.791926242221;6993.090898183305;6999.895001574981;7018.227994920555;6786.966065169642;6845.383915447634;6860.656967151623;6863.897041544381;6930.965345173343;6797.082393876863;6805.9659701701175;6809.080774095532;6811.761588344483;6838.418734407532;5371.516563509303;5456.208849716769;5459.044667183227;5495.065838807413;5511.889762204733;4739;4745;4765.833195838129;4766.237402015661;4774.522547745267;5980.820575382745;6037.426444487766;6075.939240607626;6093.177421048102;6138.754449822047;7022.613756243456;7025.578465292099;7031.683574239171;7037.788866334018;7043.049188359506;6100.938990610126;6100.969495152496;6162.784302549389;6172.969135154295;6174.043023331391;5999.820005399845;6002.939970600325;6056.06131049688;6066.059760737093;6088.726007329681;6556.541042127101;6596.86806263872;6644.966775166094;6691;6770.018347339706;7003.3346832050875;7020.157581090098;7023.578585284899;7028.824279392851;7039.648017599099;6649.235337936199;6657.201135863727;6722.596644201363;6754.054432379569;6788.355106264897;6828.829279268058;6832.7950161493245;6851.143607049119;6932.826679333057;6950.096487456598;6782.660866956731;6923.653817309215;6944.930550694428;6988.40598549129;7070.116235470567;6716.5634233774645;6717.865642687021;6722.361375669304;6730.798076057726;6734.898976515454;6631.834204144935;6733.225679046972;6739.797806065826;6756.054152418764;6758.5269031167345;6587.209534855848;6628.403443689898;6647.368499992493;6665.166854143232;6687.297833727389;6662.133922590032;6774.3225677431865;6847.383735463832;6869.587824730532;6875.5874647521305;6526.086347911201;6532.41208291237;6575.145231120116;6594.604323740591;6598.3401659833635 11:46:10 INFO - PID 13955 | |1;ext.html;716.4698123388694;758.5372922517264;771.5062360089544;771.8494893495771;1140.6692059302802;30577.235569110755;31126.68873311269;41457.585424145786;42169.15661686764;42333.57666423335;42076.368854467204;42417.36373954393;42607.786961065154;42656;42701;7352.522086064416;7851;8061.516309021477;8062.475939063971;8065.637046332899;7808.0630324361155;7933.722319718811;7951;7953.72161974331;7959.562224077674;7918.841623167533;7938.404619653549;7979;7979.122296547375;7983.880241796378;7874.5668988205625;7915.327197188246;7918.326942209919;7933.841323173504;7956.085050219183;7780.922190778077;7838.137804841463;7841.490303130278;7851.05787305527;7857.1357150713375;38057.23885522302;38305.425418618746;38330;38389;38425.615743842485;3561.661642144018;3600.315939971423;3600.47793070006;3602.1354874830113;3602.765820221677;27551.31121721953;27569.310767230978;27584.448311033866;27610.447791044066;27833.582496262574;5196.61025423091;5214.687118772886;5222.973885130588;5273.604479664003;5274.736263186864;187289;188408;188505;189922;190330;47437.288440673416;47872;47917.76041119772;47996.280055799194;48096;50056.749716251914;50424.49575504271;50573.24140137901;50599.494005060216;50605.24092138621;13945.511907083204;15402.152881591395;15649.921750391402;15654.765178522332;15682.921585392229;11182.888171118184;12990.545330913372;13107.737845243233;13133.868661313265;13147.67130821718;13840.515581954583;13843.515476958459;13940.651483712989;14021.228832414112;14085.2253126077;5681.602287839812;5707.4577915098;5779.190913272145;5852.502537284378;5887.646741195542;999.3454287441738;1011.7268337548968;1015.6648306058983;1017.2675673515057;1030.9690709278734;22264.332070037603;28338.71661283402;28452.43095138127;28814.567781483296;29194.854025729735;1090.231386872253;1097.4841824342545;1100.350793032115;1112.9833052504218;1114.6043154679971;11974.700632484084;12126.151169418055;12127.757444851053;12245.755084898252;12300.200486968319;237443;239304;239331;240101;240681;50346;50735.49264507308;50738.23892641613;50829.74585127125;50982.74508627507;1422.132499175502;1433.4126900943688;1452.9854701453062;1454.4909281751302;1466.1716130386299;22135.335939921828;22146.006859382735;22410.887945560167;22468.775312247322;22654.886725566925;36949.261014780095;37198;37321;37359.25281494409;37361.626383736904;195539;197133;197550;197827;198973;33097.83451082729;36627.26745465129;37240;37670;37683.81158094192;45434.31848522209;45589.77205113953;45946.31080533728;45999.080018398774;46120.07759844717;33231.501527477594;33345.333093338486;33486.832565837016;33577.49633755447;33824.49263261102;32951.835240823646;33036.66963330432;33296;33535.16162095967;34013.82993085019;38302;38337.23325533418;38338.42492362673;38471;38707.6129238704;32455.513167302983;32828.1792955178;33263.667363327026;33274.667253327156;33334.83332583321;23021.07915683421;23468.178613748438;23924.52150957006;24031.879840600686;24309.270721878373 11:46:10 INFO - PID 13955 | |2;jquery.html;575.6718670357898;576.2192229528986;577.5610535992645;582.0948425198817;582.6562328226348;22142.88928555357;22515.09939602414;22919.885400572995;22983.885080574575;23075.423114422127;25524;25924.092656757017;25942.22173334803;26015.349616259602;26135.085272015483;6709.798706038814;6835.589864608114;6843.486738494608;6845.075914751495;6847.075644787939;6771.559848609824;6809.387155156035;6826.2832402597505;6836.623985680785;7119.715211391538;7184.173820010687;7186.784396468088;7188.281171882796;7189.820254493653;7196.064511613483;7182.712691492334;7190.101237345332;7194.964025179866;7198.5680859148615;7203.639818009078;7005.509614326999;7022.403095736869;7031.050808140887;7031.472639552003;7062.964685176541;30758.53862192069;31582.684173158203;32024.679753202403;32143.83928080368;32202.83898580492;2029.1274751856695;2072.2229164063474;2076.678114892194;2077.293720135146;2082.6771850363216;20947.581048378946;21090.26184083565;21261.149554017822;21289.254876079416;21291.36125916225;2439.0853429963763;2459.938501537458;2502.912398066059;2565.0637517306236;2565.1663209457;134618;141502;142106;142196;142232.28883855618;20427.28504502365;21220.575588488144;22184.889075554518;22276.331710048726;22583;41637;41761.164776704296;41817.1636567267;41842.58157418448;41962.37056444156;11304.886951130547;11400;11400.201985860911;11481.196316257949;11497.137714671266;9327.253819694544;9376.812463750823;9407.529623518796;9489.430634161834;9614.759631009281;9327.533623318806;11247.887521124847;11298.943505282421;11532.443120178836;11540.307581545134;3867.45855580219;4034.677225821927;4108.239975604522;4167.5207351154395;4216.768077755692;965.3387429610739;972.3193764364831;991.4002028772488;998.2064258914181;1003.7440452684518;19896.70154947677;19974.20103195856;20325;23768.286951391485;25145.87427062853;1039.4698703661188;1043.400044974143;1047.104725459721;1058.4125810175274;1063.292910214714;10920.890791091988;11374;11471.197016208785;11638.06895448362;11705.356205408614;528530;539689;547998;552415;556163;104340.47829760954;108489.45755271173;118389;125962;126101;1209.413434484292;1225.4424236972156;1244.55818184545;1259.7795385807265;1265.2471779291307;20734.896325518275;21049.789502104784;21264.78735212628;21347.78652213458;21638.67541986903;55714.442855570924;55759;56111;57912.4208757907;57916;68112;69293;69919;70115.64942175256;70724.64637676779;14319.069260498034;14437.927810360881;15066.6986660264;15154.090754554762;15170;23587.88206058959;23858.40353991164;23868.88065559661;23917.641235381136;24027.51944961056;18618.534536636693;18751.156197971395;19105;19113.331033413764;19136.330228441937;16910.66178676444;16969.575760606083;17176.141192940053;17483.82516174873;17615.911920440314;17704.911475443056;17819.821801781814;18006.459806205836;18083.547911301797;19267.614647706687;14822.555323340317;14842.554723358317;14848.183349915858;14848.480303189337;14895.553133406016;18496.81503184951;18615.720764188278;18857.24571017199;18886.622267554296;18911.90544047271 11:46:10 INFO - PID 13955 | |3;mootools.html;1321.312917283013;1347.299404309759;1366.822313099297;1372.2178358335752;1379.8068270442145;15012.39950401984;15344.386224551004;15590.92204538977;15658.295376708062;15668.686626267496;15044.924775376136;15379.461718839815;15429.382824687027;15439.92280038601;15489.922550387233;2668.4529671417363;2736.3979924416594;2741.451709658066;2742.6708794944634;2744.300203448128;2708.214617760847;2767.7093905139973;2772.904702642455;2773.1403264987816;2774.611554382388;2667.05319611538;2688.1397952655157;2689.892404303835;2695.811293209476;2696.91909242723;2675.8260713053687;2715.9592606110923;2731.3581308392513;2732.6037724529833;2740.0409856550245;2653.6019597060404;2692.2865440658243;2708.471847989643;2715.253305341027;2719.4968930747677;14717.55847324582;15008.324625391884;15032.323545440371;15108.54674359771;15108.622284442867;7491.175970643225;7707.229277072304;7712.961435192844;7724.227577242231;7724.382049436032;5090.414602320743;5264.131418315973;5459.044667183267;5464.808731694372;5473;1898.1932678611672;1928.1901601327477;1928.4889504281377;1978.8219060284644;2000.7198992141111;26190.21429357122;26604.60093098605;27306.726932730617;27346.863265683744;27702.722972770214;7332.0834895638045;7345.816354591071;7392.297731715478;7460.962695186598;7490.925090749131;20474.38576842697;20511.692324615142;20530.38408847737;20533.897330513253;20559.280425185047;8087.393445491494;8213.630386632616;8226.683730603194;8230.753077407688;8242.629081691219;7242.311980361857;7310.195878453366;7372.152202496674;7377.963110184414;7389.224131466226;5586.720663966867;5809.605694633342;5827.3298570664065;5838.854028649318;5889.823305300848;1687.3756710017371;1754.578791179149;1789.5705030792776;1794.9641007179782;1822.6263615958962;894.2532984957634;926.2034650200821;933.7898972731217;939.262678797141;944.6929747831982;7698.307152356203;7786.727464538714;7838.764837054898;7848.764537063783;7865.4494185406475;662.9138212032404;709.6806437103324;716.5987047253554;725.4051677624403;740.988885166723;5116.641835071584;5569.721513924287;5573.247611572467;5622.831315060473;5655.349634791969;20851.374458766262;22054.558908821735;22209.666854997187;22250;22256.666150007764;20313.796862031486;20975.790242097384;21644.675329870064;21697.783022169893;21771.67342489864;9399.295052871063;9730.17293530058;10187.439690817077;11186.272892261977;11293.717657058474;8674.175953284428;9114.908850911406;9122.814034175515;9180.54097295146;9206.907930920874;17891.46325610182;18244.08779561043;18614.720779188054;18701.812981870553;18729.719054213925;18855.90572047131;19031.619367612846;19149.808501914802;19196.616067678846;19635.70546441833;12112.394380280775;12138.696532586757;12276.877231227574;12289.815652765394;12304.138710290552;13724.65688357799;13937.721245574829;13939.303034848419;13944.4422223114;13945.16329020262;11189.104871610089;11235.775284494428;11251.212415130862;11283;11534.423278835857;10860.67417977462;11033.724156895822;11127.888721112686;11293.604723834955;11540.711482213012;9205.677801276923;9413.623455061721;9424.104710052534;9447.196988256075;9449.196818270524;7008.369246767816;7030.543014704028;7068.257832927468;7070.116235470567;7127.180374257026;7043.823904402431;7079.1505019395945;7115.501914866117;7122.60825654594;7193.820154496179 11:46:10 INFO - PID 13955 | |4;prototype.html;489.63749906700156;506.2052577453395;532.294709509899;534.110705675051;543.3344153412071;6415.684784619153;6436.452901503372;6440.903386449201;6474.15486586323;6529.706163222654;6165.290991535975;6194.907076393858;6275.560710750249;6325.2409710834645;6325.272593651732;5073.797048118071;5075.949240507584;5092.0579692756955;5092.490750924896;5146.073706732796;4971.254311853215;5029.446760856303;5040.1935690289565;5079.517445842639;5085.821996230133;5044.672096313728;5063.569596584295;5063.74681265937;5076.36545431821;5096.745162741867;4989.3513843200335;5002.799888004475;5045.167547354682;5087.771050302746;5126.512981266774;4544.227481328182;4548.476925153616;4575.313702944552;4578.702384345007;4613.861584152481;6240.875182496325;6287.371262873722;6303.810885673437;6333.461655759266;6343.714532846033;5826.785821387845;5851.678157701324;5854.238948936653;5854.7072646367515;5865.677387743673;6595.8021259362295;6638.900416493757;6669.799906002828;6689.297623749485;6708.83227919301;4922.483139270396;4939.950600493985;4946.653734238605;4954.232094025432;4971.7514124294;6996.755113571052;7035.929640703579;7062.187848397397;7104.751333703347;7109.6445177741425;5017.523335283142;5056.165732654071;5060.696358218445;5077.416097148801;5088.516590923857;5102.515261050195;5130.7947682093045;5163.870903227449;5165.560927321145;5170.741462926839;5762.567807414377;5798.739056742456;5831.183634291203;5850.5027072699295;5865.970670146622;6672.733090676318;6678.666066696645;6683.188104564802;6714.354983029091;6728.629925354153;1690.2224976510697;1726.1369315342329;1747.1788259518007;1766.9823301767076;1787.7586525819108;1207.2575366149931;1229.1211783574786;1229.6434034130182;1249.1505776072177;1260.4706023470164;5876.41235876409;5909.645421274738;5934.673592952429;5973.611715238582;5975.163477113208;6598.307177746364;6652.467802575782;6772.796816095525;6898.620575868275;6968.721251149897;5945.524358051345;5956.16613674086;5971.701414929236;5989.131575921467;5994.850128746729;3654.5564502021552;3670.0457880951144;3670.541182352206;3684.299983003221;3707.1658876753054;6631.336866313427;6661;6661.433778128913;6688.163979502562;6691.698873550702;7161.283871612797;7161.9973203751515;7168.964155179191;7187.748428804966;7212.60330681807;6533.183352081085;6573;6639.86720265592;6692.765753198712;6708.865822683519;6902.689378978059;6933.791986240421;6971.372576468142;6972.755953541602;6988.40598549129;6778.288684526229;6812.455003599799;6818.352256535622;6838.7264509419065;6871.896921546281;6642.667866606747;6646.7341306349135;6656.633885136171;6667.333266673294;6692.665366731741;4985.501449855131;5031.773570189277;5061.974690126526;5062.746862656779;5092.9745351273;6544.574602651003;6846.418054465426;6883.760923033902;6919.37725604698;6999.00528349418;6356.5232607555545;6476.384743449365;6492.740290388331;6529.379708927644;6582.868342633216;6522.5760325578685;6559.409653131241;6600.174978127734;6604.9009264862025;6717.2946840581035;6370.203724534434;6442.838929026624;6456.7740129095255;6501.284858665637;6573.408393244631;6638.900416493853;6682.63245521501;6692.427279589196;6764.830879227862;6773;6408.231012278557;6451.354864513699;6470.255920569007;6513.381228783258;6535.470845743245 11:46:10 INFO - PID 13955 | |5;yui.html;915.0757734687963;916.7433118726757;934.5233930695343;935.2611436964798;939.1219210038614;18959.905200473997;19613.901930490363;19791.208351665933;19814.306499272545;21790.45523861904;20931.790682093175;21205.893970530164;21474.677879831816;21588.460288492755;21609.67585486219;979.480875136179;994.4033579852068;1001.739547717595;1005.7334806276342;1006.0291818395248;952.6237136331144;969.403816652757;969.6993931881104;971.1890571372912;973.8198433289829;1044.2272718188524;1048.811213981483;1051.8369652703843;1052.6894566103017;1052.9631462898801;1019.6635110413546;1028.3778314119925;1030.2118879057489;1037.1858091398292;1042.135027926819;761.6420282467232;768.2854944901239;769.3998681028751;771.020803579451;772.7488566215947;17728.645427091516;18476.722849157275;18899;18913.7162942556;19057.904710476498;20565.794342056688;20786.688199677017;21165.25921592737;21195.89402052995;21361.145554177816;17037.91481042599;17389.91305043467;17469;17563.5609109772;17569.912150439293;3770.1140232045445;3795.411711184771;3797.791121488317;3802.315583195024;3809.7904615246152;129690;130211;130272.3486382562;133035.33482332528;134317;16746;16815.831841681673;17112.65774684524;17136.143192840307;17302.307907683553;29921.251968700955;30699;30718.232044198627;30765.38469230603;31069;8112.959435202904;8286.0056793184;8385.454945428528;8477.660893564313;8835.64657413709;4150.626443620089;4237.724547904377;4251.5748425157235;4261.786910654455;4271.1671224111;5426.647267927572;5566.582506311962;5584.413636568183;5897.144913987534;6028.125921741323;1164.3596022187762;1183.2545496337252;1200.099925056208;1203.2780331801025;1205.626255860686;697.1007400453358;701.4283359062348;712.4656507619286;712.9643517824088;713.3722324354535;12249.816252756216;12621.873781262253;12664.81002784959;13037.217766934014;13258.602241932758;691.19475810681;708.6704682322745;713.169157931015;713.3864876206459;723.7611588175889;5499.807506737325;5581.804636837691;5832.212651292021;6406.007068904327;6451.935480645134;100483;108143;108509.91524068492;108762.45618771855;116901;93545.5322723382;94257.52871235737;96752.51623741977;97230.51384743172;97455.51272243736;86038.56980715056;87672.56163719141;87749.12250877409;90728.5463572678;91551;12600.495980160691;12968.416421261254;13008.544700935421;13209.405576749261;13310.534131305358;40047.599524004385;40323.79838100791;40358.39462408125;41570;41855;71793;73281.6335918317;73460.63269683617;73925;83634;19320;19892.403227903185;19914.90042549778;19994.500137496678;20101.59796804085;22217.111315547198;22497;23329.3001209964;23620.645690314315;23668.881655591613;19196;19561;20065.498362541053;21082.78917210808;21225.36323910285;20149.6977545334;20995.160193592696;21038.580563235424;21054.47363815917;21250.36248912535;19154.138063786886;19291.903540482206;19662;20071.29750458727;20109.49726256797;12598.937005314914;13103.541376051793;13875.306234688427;14028.29858507091;14131.576052718436;14076.085054471427;14391.784123238369;14511.927440362731;14525.273736313353;15340.693186135992 11:46:10 INFO - PID 13955 | __end_tp_report 11:46:10 INFO - PID 13955 | __start_cc_report 11:46:10 INFO - PID 13955 | _x_x_mozilla_cycle_collect,382 11:46:10 INFO - PID 13955 | __end_cc_report 11:46:10 INFO - PID 13955 | __startTimestamp1504723570863__endTimestamp 11:46:10 INFO - PID 13955 | 11:46:10 INFO - PID 13955 | ------- Summary: start ------- 11:46:10 INFO - PID 13955 | Number of tests: 6 11:46:10 INFO - PID 13955 | 11:46:10 INFO - PID 13955 | [#0] dojo.html Cycles:165 Average:6377.87 Median:6598.34 stddev:746.96 (11.3%) stddev-sans-first:709.39 11:46:10 INFO - PID 13955 | Values: 3308.9 3310.8 3327.2 3341.9 3343.1 6409.3 6465.1 6484.6 6513.2 6536.7 6366.5 6448.6 6452.9 6468.4 6495.5 5915.9 5928.7 5955.3 5985.8 5997.9 5959.7 5974.0 5986.9 6019.9 6062.4 5978.4 6042.5 6051.2 6060.1 6095.9 5888.7 5947.5 5959.1 5963.5 6058.3 5740.1 5807.3 5817.5 5849.8 5926.2 6399.3 6464.3 6487.5 6498.0 6511.7 6894.2 6905.2 6931.2 7005.8 7026.9 6742.5 6834.9 6838.0 6841.5 6846.7 6344.6 6348.5 6349.0 6359.2 6400.6 7108.8 7109.1 7198.0 7210.0 7213.2 6786.6 6830.6 6847.6 6850.6 6854.2 6907.0 6935.8 6993.1 6999.9 7018.2 6787.0 6845.4 6860.7 6863.9 6931.0 6797.1 6806.0 6809.1 6811.8 6838.4 5371.5 5456.2 5459.0 5495.1 5511.9 4739.0 4745.0 4765.8 4766.2 4774.5 5980.8 6037.4 6075.9 6093.2 6138.8 7022.6 7025.6 7031.7 7037.8 7043.0 6100.9 6101.0 6162.8 6173.0 6174.0 5999.8 6002.9 6056.1 6066.1 6088.7 6556.5 6596.9 6645.0 6691.0 6770.0 7003.3 7020.2 7023.6 7028.8 7039.6 6649.2 6657.2 6722.6 6754.1 6788.4 6828.8 6832.8 6851.1 6932.8 6950.1 6782.7 6923.7 6944.9 6988.4 7070.1 6716.6 6717.9 6722.4 6730.8 6734.9 6631.8 6733.2 6739.8 6756.1 6758.5 6587.2 6628.4 6647.4 6665.2 6687.3 6662.1 6774.3 6847.4 6869.6 6875.6 6526.1 6532.4 6575.1 6594.6 6598.3 11:46:10 INFO - PID 13955 | 11:46:10 INFO - PID 13955 | [#1] ext.html Cycles:180 Average:37906.54 Median:3601.31 stddev:54332.00 (1508.7%) stddev-sans-first:54412.66 11:46:10 INFO - PID 13955 | Values: 716.5 758.5 771.5 771.8 1140.7 30577.2 31126.7 41457.6 42169.2 42333.6 42076.4 42417.4 42607.8 42656.0 42701.0 7352.5 7851.0 8061.5 8062.5 8065.6 7808.1 7933.7 7951.0 7953.7 7959.6 7918.8 7938.4 7979.0 7979.1 7983.9 7874.6 7915.3 7918.3 7933.8 7956.1 7780.9 7838.1 7841.5 7851.1 7857.1 38057.2 38305.4 38330.0 38389.0 38425.6 3561.7 3600.3 3600.5 3602.1 3602.8 27551.3 27569.3 27584.4 27610.4 27833.6 5196.6 5214.7 5223.0 5273.6 5274.7 187289.0 188408.0 188505.0 189922.0 190330.0 47437.3 47872.0 47917.8 47996.3 48096.0 50056.7 50424.5 50573.2 50599.5 50605.2 13945.5 15402.2 15649.9 15654.8 15682.9 11182.9 12990.5 13107.7 13133.9 13147.7 13840.5 13843.5 13940.7 14021.2 14085.2 5681.6 5707.5 5779.2 5852.5 5887.6 999.3 1011.7 1015.7 1017.3 1031.0 22264.3 28338.7 28452.4 28814.6 29194.9 1090.2 1097.5 1100.4 1113.0 1114.6 11974.7 12126.2 12127.8 12245.8 12300.2 237443.0 239304.0 239331.0 240101.0 240681.0 50346.0 50735.5 50738.2 50829.7 50982.7 1422.1 1433.4 1453.0 1454.5 1466.2 22135.3 22146.0 22410.9 22468.8 22654.9 36949.3 37198.0 37321.0 37359.3 37361.6 195539.0 197133.0 197550.0 197827.0 198973.0 33097.8 36627.3 37240.0 37670.0 37683.8 45434.3 45589.8 45946.3 45999.1 46120.1 33231.5 33345.3 33486.8 33577.5 33824.5 32951.8 33036.7 33296.0 33535.2 34013.8 38302.0 38337.2 38338.4 38471.0 38707.6 32455.5 32828.2 33263.7 33274.7 33334.8 23021.1 23468.2 23924.5 24031.9 24309.3 11:46:10 INFO - PID 13955 | 11:46:10 INFO - PID 13955 | [#2] jquery.html Cycles:180 Average:37614.16 Median:22951.89 stddev:91013.34 (396.5%) stddev-sans-first:91226.17 11:46:10 INFO - PID 13955 | Values: 575.7 576.2 577.6 582.1 582.7 22142.9 22515.1 22919.9 22983.9 23075.4 25524.0 25924.1 25942.2 26015.3 26135.1 6709.8 6835.6 6843.5 6845.1 6847.1 6771.6 6809.4 6826.3 6836.6 7119.7 7184.2 7186.8 7188.3 7189.8 7196.1 7182.7 7190.1 7195.0 7198.6 7203.6 7005.5 7022.4 7031.1 7031.5 7063.0 30758.5 31582.7 32024.7 32143.8 32202.8 2029.1 2072.2 2076.7 2077.3 2082.7 20947.6 21090.3 21261.1 21289.3 21291.4 2439.1 2459.9 2502.9 2565.1 2565.2 134618.0 141502.0 142106.0 142196.0 142232.3 20427.3 21220.6 22184.9 22276.3 22583.0 41637.0 41761.2 41817.2 41842.6 41962.4 11304.9 11400.0 11400.2 11481.2 11497.1 9327.3 9376.8 9407.5 9489.4 9614.8 9327.5 11247.9 11298.9 11532.4 11540.3 3867.5 4034.7 4108.2 4167.5 4216.8 965.3 972.3 991.4 998.2 1003.7 19896.7 19974.2 20325.0 23768.3 25145.9 1039.5 1043.4 1047.1 1058.4 1063.3 10920.9 11374.0 11471.2 11638.1 11705.4 528530.0 539689.0 547998.0 552415.0 556163.0 104340.5 108489.5 118389.0 125962.0 126101.0 1209.4 1225.4 1244.6 1259.8 1265.2 20734.9 21049.8 21264.8 21347.8 21638.7 55714.4 55759.0 56111.0 57912.4 57916.0 68112.0 69293.0 69919.0 70115.6 70724.6 14319.1 14437.9 15066.7 15154.1 15170.0 23587.9 23858.4 23868.9 23917.6 24027.5 18618.5 18751.2 19105.0 19113.3 19136.3 16910.7 16969.6 17176.1 17483.8 17615.9 17704.9 17819.8 18006.5 18083.5 19267.6 14822.6 14842.6 14848.2 14848.5 14895.6 18496.8 18615.7 18857.2 18886.6 18911.9 11:46:10 INFO - PID 13955 | 11:46:10 INFO - PID 13955 | [#3] mootools.html Cycles:180 Average:9475.57 Median:2711.86 stddev:6830.80 (251.9%) stddev-sans-first:6822.49 11:46:10 INFO - PID 13955 | Values: 1321.3 1347.3 1366.8 1372.2 1379.8 15012.4 15344.4 15590.9 15658.3 15668.7 15044.9 15379.5 15429.4 15439.9 15489.9 2668.5 2736.4 2741.5 2742.7 2744.3 2708.2 2767.7 2772.9 2773.1 2774.6 2667.1 2688.1 2689.9 2695.8 2696.9 2675.8 2716.0 2731.4 2732.6 2740.0 2653.6 2692.3 2708.5 2715.3 2719.5 14717.6 15008.3 15032.3 15108.5 15108.6 7491.2 7707.2 7713.0 7724.2 7724.4 5090.4 5264.1 5459.0 5464.8 5473.0 1898.2 1928.2 1928.5 1978.8 2000.7 26190.2 26604.6 27306.7 27346.9 27702.7 7332.1 7345.8 7392.3 7461.0 7490.9 20474.4 20511.7 20530.4 20533.9 20559.3 8087.4 8213.6 8226.7 8230.8 8242.6 7242.3 7310.2 7372.2 7378.0 7389.2 5586.7 5809.6 5827.3 5838.9 5889.8 1687.4 1754.6 1789.6 1795.0 1822.6 894.3 926.2 933.8 939.3 944.7 7698.3 7786.7 7838.8 7848.8 7865.4 662.9 709.7 716.6 725.4 741.0 5116.6 5569.7 5573.2 5622.8 5655.3 20851.4 22054.6 22209.7 22250.0 22256.7 20313.8 20975.8 21644.7 21697.8 21771.7 9399.3 9730.2 10187.4 11186.3 11293.7 8674.2 9114.9 9122.8 9180.5 9206.9 17891.5 18244.1 18614.7 18701.8 18729.7 18855.9 19031.6 19149.8 19196.6 19635.7 12112.4 12138.7 12276.9 12289.8 12304.1 13724.7 13937.7 13939.3 13944.4 13945.2 11189.1 11235.8 11251.2 11283.0 11534.4 10860.7 11033.7 11127.9 11293.6 11540.7 9205.7 9413.6 9424.1 9447.2 9449.2 7008.4 7030.5 7068.3 7070.1 7127.2 7043.8 7079.2 7115.5 7122.6 7193.8 11:46:10 INFO - PID 13955 | 11:46:10 INFO - PID 13955 | [#4] prototype.html Cycles:180 Average:5624.86 Median:6314.53 stddev:1585.77 (25.1%) stddev-sans-first:1542.66 11:46:10 INFO - PID 13955 | Values: 489.6 506.2 532.3 534.1 543.3 6415.7 6436.5 6440.9 6474.2 6529.7 6165.3 6194.9 6275.6 6325.2 6325.3 5073.8 5075.9 5092.1 5092.5 5146.1 4971.3 5029.4 5040.2 5079.5 5085.8 5044.7 5063.6 5063.7 5076.4 5096.7 4989.4 5002.8 5045.2 5087.8 5126.5 4544.2 4548.5 4575.3 4578.7 4613.9 6240.9 6287.4 6303.8 6333.5 6343.7 5826.8 5851.7 5854.2 5854.7 5865.7 6595.8 6638.9 6669.8 6689.3 6708.8 4922.5 4940.0 4946.7 4954.2 4971.8 6996.8 7035.9 7062.2 7104.8 7109.6 5017.5 5056.2 5060.7 5077.4 5088.5 5102.5 5130.8 5163.9 5165.6 5170.7 5762.6 5798.7 5831.2 5850.5 5866.0 6672.7 6678.7 6683.2 6714.4 6728.6 1690.2 1726.1 1747.2 1767.0 1787.8 1207.3 1229.1 1229.6 1249.2 1260.5 5876.4 5909.6 5934.7 5973.6 5975.2 6598.3 6652.5 6772.8 6898.6 6968.7 5945.5 5956.2 5971.7 5989.1 5994.9 3654.6 3670.0 3670.5 3684.3 3707.2 6631.3 6661.0 6661.4 6688.2 6691.7 7161.3 7162.0 7169.0 7187.7 7212.6 6533.2 6573.0 6639.9 6692.8 6708.9 6902.7 6933.8 6971.4 6972.8 6988.4 6778.3 6812.5 6818.4 6838.7 6871.9 6642.7 6646.7 6656.6 6667.3 6692.7 4985.5 5031.8 5062.0 5062.7 5093.0 6544.6 6846.4 6883.8 6919.4 6999.0 6356.5 6476.4 6492.7 6529.4 6582.9 6522.6 6559.4 6600.2 6604.9 6717.3 6370.2 6442.8 6456.8 6501.3 6573.4 6638.9 6682.6 6692.4 6764.8 6773.0 6408.2 6451.4 6470.3 6513.4 6535.5 11:46:10 INFO - PID 13955 | 11:46:10 INFO - PID 13955 | [#5] yui.html Cycles:180 Average:24467.00 Median:21215.63 stddev:32672.85 (154.0%) stddev-sans-first:32716.65 11:46:10 INFO - PID 13955 | Values: 915.1 916.7 934.5 935.3 939.1 18959.9 19613.9 19791.2 19814.3 21790.5 20931.8 21205.9 21474.7 21588.5 21609.7 979.5 994.4 1001.7 1005.7 1006.0 952.6 969.4 969.7 971.2 973.8 1044.2 1048.8 1051.8 1052.7 1053.0 1019.7 1028.4 1030.2 1037.2 1042.1 761.6 768.3 769.4 771.0 772.7 17728.6 18476.7 18899.0 18913.7 19057.9 20565.8 20786.7 21165.3 21195.9 21361.1 17037.9 17389.9 17469.0 17563.6 17569.9 3770.1 3795.4 3797.8 3802.3 3809.8 129690.0 130211.0 130272.3 133035.3 134317.0 16746.0 16815.8 17112.7 17136.1 17302.3 29921.3 30699.0 30718.2 30765.4 31069.0 8113.0 8286.0 8385.5 8477.7 8835.6 4150.6 4237.7 4251.6 4261.8 4271.2 5426.6 5566.6 5584.4 5897.1 6028.1 1164.4 1183.3 1200.1 1203.3 1205.6 697.1 701.4 712.5 713.0 713.4 12249.8 12621.9 12664.8 13037.2 13258.6 691.2 708.7 713.2 713.4 723.8 5499.8 5581.8 5832.2 6406.0 6451.9 100483.0 108143.0 108509.9 108762.5 116901.0 93545.5 94257.5 96752.5 97230.5 97455.5 86038.6 87672.6 87749.1 90728.5 91551.0 12600.5 12968.4 13008.5 13209.4 13310.5 40047.6 40323.8 40358.4 41570.0 41855.0 71793.0 73281.6 73460.6 73925.0 83634.0 19320.0 19892.4 19914.9 19994.5 20101.6 22217.1 22497.0 23329.3 23620.6 23668.9 19196.0 19561.0 20065.5 21082.8 21225.4 20149.7 20995.2 21038.6 21054.5 21250.4 19154.1 19291.9 19662.0 20071.3 20109.5 12598.9 13103.5 13875.3 14028.3 14131.6 14076.1 14391.8 14511.9 14525.3 15340.7 11:46:10 INFO - PID 13955 | -------- Summary: end -------- 11:46:10 INFO - PID 13955 | 11:46:11 INFO - TEST-INFO | 13955: exit 0 11:46:11 INFO - TEST-OK | dromaeo_css | took 1108937ms 11:46:11 INFO - TEST-START | kraken 11:46:11 INFO - Initialising browser for kraken test... 11:46:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34889/getInfo.html -profile /tmp/tmpR4qMwx/profile 11:46:11 INFO - TEST-INFO | started process 14329 (/builds/slave/test/build/application/firefox/firefox http://localhost:34889/getInfo.html) 11:46:17 INFO - TEST-INFO | 14329: exit 0 11:46:17 INFO - Browser initialized. 11:46:17 INFO - Running cycle 1/1 for kraken test... 11:46:17 INFO - Using env: {'DISPLAY': ':0', 11:46:17 INFO - 'HOME': '/home/cltbld', 11:46:17 INFO - 'JSGC_DISABLE_POISONING': '1', 11:46:17 INFO - 'LANG': 'en_US.UTF-8', 11:46:17 INFO - 'LANGUAGE': 'en_US:en', 11:46:17 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:46:17 INFO - 'LOGNAME': 'cltbld', 11:46:17 INFO - 'MAIL': '/var/mail/cltbld', 11:46:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:46:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:46:17 INFO - 'MOZ_CRASHREPORTER': '1', 11:46:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:17 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:46:17 INFO - 'MOZ_NO_REMOTE': '1', 11:46:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:46:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:46:17 INFO - 'NO_EM_RESTART': '1', 11:46:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:46:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:17 INFO - 'PWD': '/builds/slave/test', 11:46:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:46:17 INFO - 'RUST_BACKTRACE': 'full', 11:46:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:46:17 INFO - 'SHELL': '/bin/bash', 11:46:17 INFO - 'SHLVL': '1', 11:46:17 INFO - 'STYLO_FORCE_DISABLED': '1', 11:46:17 INFO - 'TERM': 'linux', 11:46:17 INFO - 'TMOUT': '86400', 11:46:17 INFO - 'USER': 'cltbld', 11:46:17 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814', 11:46:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:17 INFO - '_': '/tools/buildbot/bin/python'} 11:46:17 INFO - TEST-INFO | started process 14528 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR4qMwx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:46:18 INFO - PID 14528 | 11:46:18 INFO - PID 14528 | (/builds/slave/test/build/application/firefox/firefox:14580): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:46:18 INFO - PID 14528 | 11:46:28 INFO - PID 14528 | RSS: Main: 170176512 11:46:28 INFO - PID 14528 | 11:46:31 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34889/tests/kraken/driver.html?testName=audio-beat-detection) 11:46:32 INFO - PID 14528 | RSS: Main: 176300032 11:46:32 INFO - PID 14528 | 11:46:35 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34889/tests/kraken/driver.html?testName=audio-dft) 11:46:35 INFO - PID 14528 | RSS: Main: 176934912 11:46:35 INFO - PID 14528 | 11:46:38 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34889/tests/kraken/driver.html?testName=audio-fft) 11:46:39 INFO - PID 14528 | RSS: Main: 170897408 11:46:39 INFO - PID 14528 | 11:46:41 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34889/tests/kraken/driver.html?testName=audio-oscillator) 11:46:41 INFO - PID 14528 | RSS: Main: 171552768 11:46:41 INFO - PID 14528 | 11:46:44 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34889/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:46:44 INFO - PID 14528 | RSS: Main: 172015616 11:46:44 INFO - PID 14528 | 11:46:47 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34889/tests/kraken/driver.html?testName=imaging-darkroom) 11:46:48 INFO - PID 14528 | RSS: Main: 174837760 11:46:48 INFO - PID 14528 | 11:46:51 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34889/tests/kraken/driver.html?testName=imaging-desaturate) 11:46:52 INFO - PID 14528 | RSS: Main: 174825472 11:46:52 INFO - PID 14528 | 11:46:55 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34889/tests/kraken/driver.html?testName=json-parse-financial) 11:46:55 INFO - PID 14528 | RSS: Main: 174923776 11:46:55 INFO - PID 14528 | 11:46:57 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34889/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:46:58 INFO - PID 14528 | RSS: Main: 174940160 11:46:58 INFO - PID 14528 | 11:46:59 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34889/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:47:00 INFO - PID 14528 | RSS: Main: 174776320 11:47:00 INFO - PID 14528 | 11:47:02 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34889/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:47:03 INFO - PID 14528 | RSS: Main: 171597824 11:47:03 INFO - PID 14528 | 11:47:05 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:47:06 INFO - PID 14528 | RSS: Main: 171864064 11:47:06 INFO - PID 14528 | 11:47:09 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:47:10 INFO - PID 14528 | RSS: Main: 172507136 11:47:10 INFO - PID 14528 | 11:47:11 INFO - PID 14528 | Cycle 1(1): loaded http://localhost:34889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34889/tests/kraken/driver.html?testName=ai-astar) 11:47:12 INFO - PID 14528 | RSS: Main: 174096384 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | __start_tp_report 11:47:12 INFO - PID 14528 | _x_x_mozilla_page_load 11:47:12 INFO - PID 14528 | _x_x_mozilla_page_load_details 11:47:12 INFO - PID 14528 | |i|pagename|runs| 11:47:12 INFO - PID 14528 | |0;ai-astar;100;115;103;108;108;108;109;99;107;108 11:47:12 INFO - PID 14528 | |1;audio-beat-detection;142;119;151;179;117;146;151;122;174;119 11:47:12 INFO - PID 14528 | |2;audio-dft;164;161;161;162;162;161;164;165;167;163 11:47:12 INFO - PID 14528 | |3;audio-fft;74;75;75;174;76;74;76;74;149;76 11:47:12 INFO - PID 14528 | |4;audio-oscillator;78;79;131;78;79;80;130;79;80;128 11:47:12 INFO - PID 14528 | |5;imaging-gaussian-blur;114;114;114;115;115;114;114;114;114;114 11:47:12 INFO - PID 14528 | |6;imaging-darkroom;148;148;147;147;149;147;148;148;150;148 11:47:12 INFO - PID 14528 | |7;imaging-desaturate;93;93;94;94;94;96;95;94;94;94 11:47:12 INFO - PID 14528 | |8;json-parse-financial;54;54;56;51;51;52;51;52;51;53 11:47:12 INFO - PID 14528 | |9;json-stringify-tinderbox;38;37;38;38;38;38;38;38;38;38 11:47:12 INFO - PID 14528 | |10;stanford-crypto-aes;74;78;76;74;77;76;77;75;77;76 11:47:12 INFO - PID 14528 | |11;stanford-crypto-ccm;128;129;120;118;123;135;123;121;119;163 11:47:12 INFO - PID 14528 | |12;stanford-crypto-pbkdf2;182;172;173;180;169;184;174;168;168;179 11:47:12 INFO - PID 14528 | |13;stanford-crypto-sha256-iterative;72;64;67;63;65;66;66;66;66;64 11:47:12 INFO - PID 14528 | __end_tp_report 11:47:12 INFO - PID 14528 | __start_cc_report 11:47:12 INFO - PID 14528 | _x_x_mozilla_cycle_collect,937 11:47:12 INFO - PID 14528 | __end_cc_report 11:47:12 INFO - PID 14528 | __startTimestamp1504723632191__endTimestamp 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | ------- Summary: start ------- 11:47:12 INFO - PID 14528 | Number of tests: 14 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#0] ai-astar Cycles:10 Average:106.50 Median:108.00 stddev:4.70 (4.3%) stddev-sans-first:4.35 11:47:12 INFO - PID 14528 | Values: 100.0 115.0 103.0 108.0 108.0 108.0 109.0 99.0 107.0 108.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:148.50 stddev:22.74 (15.3%) stddev-sans-first:24.12 11:47:12 INFO - PID 14528 | Values: 142.0 119.0 151.0 179.0 117.0 146.0 151.0 122.0 174.0 119.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#2] audio-dft Cycles:10 Average:163.00 Median:163.50 stddev:2.00 (1.2%) stddev-sans-first:2.09 11:47:12 INFO - PID 14528 | Values: 164.0 161.0 161.0 162.0 162.0 161.0 164.0 165.0 167.0 163.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#3] audio-fft Cycles:10 Average:92.30 Median:75.00 stddev:36.95 (49.3%) stddev-sans-first:38.60 11:47:12 INFO - PID 14528 | Values: 74.0 75.0 75.0 174.0 76.0 74.0 76.0 74.0 149.0 76.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#4] audio-oscillator Cycles:10 Average:94.20 Median:79.00 stddev:24.49 (31.0%) stddev-sans-first:25.27 11:47:12 INFO - PID 14528 | Values: 78.0 79.0 131.0 78.0 79.0 80.0 130.0 79.0 80.0 128.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 11:47:12 INFO - PID 14528 | Values: 114.0 114.0 114.0 115.0 115.0 114.0 114.0 114.0 114.0 114.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 11:47:12 INFO - PID 14528 | Values: 148.0 148.0 147.0 147.0 149.0 147.0 148.0 148.0 150.0 148.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.88 (0.9%) stddev-sans-first:0.83 11:47:12 INFO - PID 14528 | Values: 93.0 93.0 94.0 94.0 94.0 96.0 95.0 94.0 94.0 94.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:1.72 (3.3%) stddev-sans-first:1.73 11:47:12 INFO - PID 14528 | Values: 54.0 54.0 56.0 51.0 51.0 52.0 51.0 52.0 51.0 53.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#9] json-stringify-tinderbox Cycles:10 Average:37.90 Median:38.00 stddev:0.32 (0.8%) stddev-sans-first:0.33 11:47:12 INFO - PID 14528 | Values: 38.0 37.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:76.50 stddev:1.33 (1.7%) stddev-sans-first:1.20 11:47:12 INFO - PID 14528 | Values: 74.0 78.0 76.0 74.0 77.0 76.0 77.0 75.0 77.0 76.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:125.50 stddev:13.41 (10.7%) stddev-sans-first:14.23 11:47:12 INFO - PID 14528 | Values: 128.0 129.0 120.0 118.0 123.0 135.0 123.0 121.0 119.0 163.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.90 Median:176.50 stddev:5.95 (3.4%) stddev-sans-first:5.73 11:47:12 INFO - PID 14528 | Values: 182.0 172.0 173.0 180.0 169.0 184.0 174.0 168.0 168.0 179.0 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:2.47 (3.7%) stddev-sans-first:1.30 11:47:12 INFO - PID 14528 | Values: 72.0 64.0 67.0 63.0 65.0 66.0 66.0 66.0 66.0 64.0 11:47:12 INFO - PID 14528 | -------- Summary: end -------- 11:47:12 INFO - PID 14528 | 11:47:12 INFO - PID 14528 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:47:12 INFO - TEST-INFO | 14528: exit 0 11:47:12 INFO - TEST-OK | kraken | took 61172ms 11:47:12 INFO - SUITE-END | took 1170s 11:47:13 INFO - Completed test suite (00:19:30) 11:47:13 INFO - javascript benchmark 11:47:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8808.023991680213, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.9338213235737, 3310.768246222764, 3327.1515763480315, 3341.9164520886975, 3343.063942096214, 6409.302279118321, 6465.062565927938, 6484.578502397341, 6513.185851768529, 6536.705848236829, 6366.458850997659, 6448.613083214998, 6452.870942581146, 6468.417842394184, 6495.480361571074, 5915.88168236635, 5928.703564821763, 5955.255593050869, 5985.760569577234, 5997.910031349533, 5959.731812068468, 5974.044152935531, 5986.940130598682, 6019.879602407949, 6062.363451837559, 5978.40215978403, 6042.516598672096, 6051.243594550681, 6060.060690592965, 6095.878082438348, 5888.705564721789, 5947.4944629706315, 5959.0763431668165, 5963.522918166536, 6058.273007239115, 5740.052891272935, 5807.332156801979, 5817.505512031483, 5849.7660093596205, 5926.199963004982, 6399.328070552571, 6464.25661048976, 6487.481001519867, 6497.967510162466, 6511.674416279215, 6894.207166175904, 6905.240423553405, 6931.168259808805, 7005.754798582077, 7026.894596581055, 6742.460603151736, 6834.897476537856, 6838.042674025641, 6841.521093523455, 6846.691898864513, 6344.619322840645, 6348.492120630339, 6348.968255158741, 6359.20509936258, 6400.583962042452, 7108.822279443055, 7109.111361079865, 7197.992281080653, 7210.0, 7213.1704853942465, 6786.558873673195, 6830.556013859084, 6847.589144651337, 6850.588964662136, 6854.177498700188, 6906.965465172642, 6935.791926242221, 6993.090898183305, 6999.895001574981, 7018.227994920555, 6786.966065169642, 6845.383915447634, 6860.656967151623, 6863.897041544381, 6930.965345173343, 6797.082393876863, 6805.9659701701175, 6809.080774095532, 6811.761588344483, 6838.418734407532, 5371.516563509303, 5456.208849716769, 5459.044667183227, 5495.065838807413, 5511.889762204733, 4739.0, 4745.0, 4765.833195838129, 4766.237402015661, 4774.522547745267, 5980.820575382745, 6037.426444487766, 6075.939240607626, 6093.177421048102, 6138.754449822047, 7022.613756243456, 7025.578465292099, 7031.683574239171, 7037.788866334018, 7043.049188359506, 6100.938990610126, 6100.969495152496, 6162.784302549389, 6172.969135154295, 6174.043023331391, 5999.820005399845, 6002.939970600325, 6056.06131049688, 6066.059760737093, 6088.726007329681, 6556.541042127101, 6596.86806263872, 6644.966775166094, 6691.0, 6770.018347339706, 7003.3346832050875, 7020.157581090098, 7023.578585284899, 7028.824279392851, 7039.648017599099, 6649.235337936199, 6657.201135863727, 6722.596644201363, 6754.054432379569, 6788.355106264897, 6828.829279268058, 6832.7950161493245, 6851.143607049119, 6932.826679333057, 6950.096487456598, 6782.660866956731, 6923.653817309215, 6944.930550694428, 6988.40598549129, 7070.116235470567, 6716.5634233774645, 6717.865642687021, 6722.361375669304, 6730.798076057726, 6734.898976515454, 6631.834204144935, 6733.225679046972, 6739.797806065826, 6756.054152418764, 6758.5269031167345, 6587.209534855848, 6628.403443689898, 6647.368499992493, 6665.166854143232, 6687.297833727389, 6662.133922590032, 6774.3225677431865, 6847.383735463832, 6869.587824730532, 6875.5874647521305, 6526.086347911201, 6532.41208291237, 6575.145231120116, 6594.604323740591, 6598.3401659833635], "value": 6322.486476224916, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.4698123388694, 758.5372922517264, 771.5062360089544, 771.8494893495771, 1140.6692059302802, 30577.235569110755, 31126.68873311269, 41457.585424145786, 42169.15661686764, 42333.57666423335, 42076.368854467204, 42417.36373954393, 42607.786961065154, 42656.0, 42701.0, 7352.522086064416, 7851.0, 8061.516309021477, 8062.475939063971, 8065.637046332899, 7808.0630324361155, 7933.722319718811, 7951.0, 7953.72161974331, 7959.562224077674, 7918.841623167533, 7938.404619653549, 7979.0, 7979.122296547375, 7983.880241796378, 7874.5668988205625, 7915.327197188246, 7918.326942209919, 7933.841323173504, 7956.085050219183, 7780.922190778077, 7838.137804841463, 7841.490303130278, 7851.05787305527, 7857.1357150713375, 38057.23885522302, 38305.425418618746, 38330.0, 38389.0, 38425.615743842485, 3561.661642144018, 3600.315939971423, 3600.47793070006, 3602.1354874830113, 3602.765820221677, 27551.31121721953, 27569.310767230978, 27584.448311033866, 27610.447791044066, 27833.582496262574, 5196.61025423091, 5214.687118772886, 5222.973885130588, 5273.604479664003, 5274.736263186864, 187289.0, 188408.0, 188505.0, 189922.0, 190330.0, 47437.288440673416, 47872.0, 47917.76041119772, 47996.280055799194, 48096.0, 50056.749716251914, 50424.49575504271, 50573.24140137901, 50599.494005060216, 50605.24092138621, 13945.511907083204, 15402.152881591395, 15649.921750391402, 15654.765178522332, 15682.921585392229, 11182.888171118184, 12990.545330913372, 13107.737845243233, 13133.868661313265, 13147.67130821718, 13840.515581954583, 13843.515476958459, 13940.651483712989, 14021.228832414112, 14085.2253126077, 5681.602287839812, 5707.4577915098, 5779.190913272145, 5852.502537284378, 5887.646741195542, 999.3454287441738, 1011.7268337548968, 1015.6648306058983, 1017.2675673515057, 1030.9690709278734, 22264.332070037603, 28338.71661283402, 28452.43095138127, 28814.567781483296, 29194.854025729735, 1090.231386872253, 1097.4841824342545, 1100.350793032115, 1112.9833052504218, 1114.6043154679971, 11974.700632484084, 12126.151169418055, 12127.757444851053, 12245.755084898252, 12300.200486968319, 237443.0, 239304.0, 239331.0, 240101.0, 240681.0, 50346.0, 50735.49264507308, 50738.23892641613, 50829.74585127125, 50982.74508627507, 1422.132499175502, 1433.4126900943688, 1452.9854701453062, 1454.4909281751302, 1466.1716130386299, 22135.335939921828, 22146.006859382735, 22410.887945560167, 22468.775312247322, 22654.886725566925, 36949.261014780095, 37198.0, 37321.0, 37359.25281494409, 37361.626383736904, 195539.0, 197133.0, 197550.0, 197827.0, 198973.0, 33097.83451082729, 36627.26745465129, 37240.0, 37670.0, 37683.81158094192, 45434.31848522209, 45589.77205113953, 45946.31080533728, 45999.080018398774, 46120.07759844717, 33231.501527477594, 33345.333093338486, 33486.832565837016, 33577.49633755447, 33824.49263261102, 32951.835240823646, 33036.66963330432, 33296.0, 33535.16162095967, 34013.82993085019, 38302.0, 38337.23325533418, 38338.42492362673, 38471.0, 38707.6129238704, 32455.513167302983, 32828.1792955178, 33263.667363327026, 33274.667253327156, 33334.83332583321, 23021.07915683421, 23468.178613748438, 23924.52150957006, 24031.879840600686, 24309.270721878373], "value": 17276.606900712788, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.6718670357898, 576.2192229528986, 577.5610535992645, 582.0948425198817, 582.6562328226348, 22142.88928555357, 22515.09939602414, 22919.885400572995, 22983.885080574575, 23075.423114422127, 25524.0, 25924.092656757017, 25942.22173334803, 26015.349616259602, 26135.085272015483, 6709.798706038814, 6835.589864608114, 6843.486738494608, 6845.075914751495, 6847.075644787939, 6771.559848609824, 6809.387155156035, 6826.2832402597505, 6836.623985680785, 7119.715211391538, 7184.173820010687, 7186.784396468088, 7188.281171882796, 7189.820254493653, 7196.064511613483, 7182.712691492334, 7190.101237345332, 7194.964025179866, 7198.5680859148615, 7203.639818009078, 7005.509614326999, 7022.403095736869, 7031.050808140887, 7031.472639552003, 7062.964685176541, 30758.53862192069, 31582.684173158203, 32024.679753202403, 32143.83928080368, 32202.83898580492, 2029.1274751856695, 2072.2229164063474, 2076.678114892194, 2077.293720135146, 2082.6771850363216, 20947.581048378946, 21090.26184083565, 21261.149554017822, 21289.254876079416, 21291.36125916225, 2439.0853429963763, 2459.938501537458, 2502.912398066059, 2565.0637517306236, 2565.1663209457, 134618.0, 141502.0, 142106.0, 142196.0, 142232.28883855618, 20427.28504502365, 21220.575588488144, 22184.889075554518, 22276.331710048726, 22583.0, 41637.0, 41761.164776704296, 41817.1636567267, 41842.58157418448, 41962.37056444156, 11304.886951130547, 11400.0, 11400.201985860911, 11481.196316257949, 11497.137714671266, 9327.253819694544, 9376.812463750823, 9407.529623518796, 9489.430634161834, 9614.759631009281, 9327.533623318806, 11247.887521124847, 11298.943505282421, 11532.443120178836, 11540.307581545134, 3867.45855580219, 4034.677225821927, 4108.239975604522, 4167.5207351154395, 4216.768077755692, 965.3387429610739, 972.3193764364831, 991.4002028772488, 998.2064258914181, 1003.7440452684518, 19896.70154947677, 19974.20103195856, 20325.0, 23768.286951391485, 25145.87427062853, 1039.4698703661188, 1043.400044974143, 1047.104725459721, 1058.4125810175274, 1063.292910214714, 10920.890791091988, 11374.0, 11471.197016208785, 11638.06895448362, 11705.356205408614, 528530.0, 539689.0, 547998.0, 552415.0, 556163.0, 104340.47829760954, 108489.45755271173, 118389.0, 125962.0, 126101.0, 1209.413434484292, 1225.4424236972156, 1244.55818184545, 1259.7795385807265, 1265.2471779291307, 20734.896325518275, 21049.789502104784, 21264.78735212628, 21347.78652213458, 21638.67541986903, 55714.442855570924, 55759.0, 56111.0, 57912.4208757907, 57916.0, 68112.0, 69293.0, 69919.0, 70115.64942175256, 70724.64637676779, 14319.069260498034, 14437.927810360881, 15066.6986660264, 15154.090754554762, 15170.0, 23587.88206058959, 23858.40353991164, 23868.88065559661, 23917.641235381136, 24027.51944961056, 18618.534536636693, 18751.156197971395, 19105.0, 19113.331033413764, 19136.330228441937, 16910.66178676444, 16969.575760606083, 17176.141192940053, 17483.82516174873, 17615.911920440314, 17704.911475443056, 17819.821801781814, 18006.459806205836, 18083.547911301797, 19267.614647706687, 14822.555323340317, 14842.554723358317, 14848.183349915858, 14848.480303189337, 14895.553133406016, 18496.81503184951, 18615.720764188278, 18857.24571017199, 18886.622267554296, 18911.90544047271], "value": 12995.716507143547, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.312917283013, 1347.299404309759, 1366.822313099297, 1372.2178358335752, 1379.8068270442145, 15012.39950401984, 15344.386224551004, 15590.92204538977, 15658.295376708062, 15668.686626267496, 15044.924775376136, 15379.461718839815, 15429.382824687027, 15439.92280038601, 15489.922550387233, 2668.4529671417363, 2736.3979924416594, 2741.451709658066, 2742.6708794944634, 2744.300203448128, 2708.214617760847, 2767.7093905139973, 2772.904702642455, 2773.1403264987816, 2774.611554382388, 2667.05319611538, 2688.1397952655157, 2689.892404303835, 2695.811293209476, 2696.91909242723, 2675.8260713053687, 2715.9592606110923, 2731.3581308392513, 2732.6037724529833, 2740.0409856550245, 2653.6019597060404, 2692.2865440658243, 2708.471847989643, 2715.253305341027, 2719.4968930747677, 14717.55847324582, 15008.324625391884, 15032.323545440371, 15108.54674359771, 15108.622284442867, 7491.175970643225, 7707.229277072304, 7712.961435192844, 7724.227577242231, 7724.382049436032, 5090.414602320743, 5264.131418315973, 5459.044667183267, 5464.808731694372, 5473.0, 1898.1932678611672, 1928.1901601327477, 1928.4889504281377, 1978.8219060284644, 2000.7198992141111, 26190.21429357122, 26604.60093098605, 27306.726932730617, 27346.863265683744, 27702.722972770214, 7332.0834895638045, 7345.816354591071, 7392.297731715478, 7460.962695186598, 7490.925090749131, 20474.38576842697, 20511.692324615142, 20530.38408847737, 20533.897330513253, 20559.280425185047, 8087.393445491494, 8213.630386632616, 8226.683730603194, 8230.753077407688, 8242.629081691219, 7242.311980361857, 7310.195878453366, 7372.152202496674, 7377.963110184414, 7389.224131466226, 5586.720663966867, 5809.605694633342, 5827.3298570664065, 5838.854028649318, 5889.823305300848, 1687.3756710017371, 1754.578791179149, 1789.5705030792776, 1794.9641007179782, 1822.6263615958962, 894.2532984957634, 926.2034650200821, 933.7898972731217, 939.262678797141, 944.6929747831982, 7698.307152356203, 7786.727464538714, 7838.764837054898, 7848.764537063783, 7865.4494185406475, 662.9138212032404, 709.6806437103324, 716.5987047253554, 725.4051677624403, 740.988885166723, 5116.641835071584, 5569.721513924287, 5573.247611572467, 5622.831315060473, 5655.349634791969, 20851.374458766262, 22054.558908821735, 22209.666854997187, 22250.0, 22256.666150007764, 20313.796862031486, 20975.790242097384, 21644.675329870064, 21697.783022169893, 21771.67342489864, 9399.295052871063, 9730.17293530058, 10187.439690817077, 11186.272892261977, 11293.717657058474, 8674.175953284428, 9114.908850911406, 9122.814034175515, 9180.54097295146, 9206.907930920874, 17891.46325610182, 18244.08779561043, 18614.720779188054, 18701.812981870553, 18729.719054213925, 18855.90572047131, 19031.619367612846, 19149.808501914802, 19196.616067678846, 19635.70546441833, 12112.394380280775, 12138.696532586757, 12276.877231227574, 12289.815652765394, 12304.138710290552, 13724.65688357799, 13937.721245574829, 13939.303034848419, 13944.4422223114, 13945.16329020262, 11189.104871610089, 11235.775284494428, 11251.212415130862, 11283.0, 11534.423278835857, 10860.67417977462, 11033.724156895822, 11127.888721112686, 11293.604723834955, 11540.711482213012, 9205.677801276923, 9413.623455061721, 9424.104710052534, 9447.196988256075, 9449.196818270524, 7008.369246767816, 7030.543014704028, 7068.257832927468, 7070.116235470567, 7127.180374257026, 7043.823904402431, 7079.1505019395945, 7115.501914866117, 7122.60825654594, 7193.820154496179], "value": 6730.735117107308, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.63749906700156, 506.2052577453395, 532.294709509899, 534.110705675051, 543.3344153412071, 6415.684784619153, 6436.452901503372, 6440.903386449201, 6474.15486586323, 6529.706163222654, 6165.290991535975, 6194.907076393858, 6275.560710750249, 6325.2409710834645, 6325.272593651732, 5073.797048118071, 5075.949240507584, 5092.0579692756955, 5092.490750924896, 5146.073706732796, 4971.254311853215, 5029.446760856303, 5040.1935690289565, 5079.517445842639, 5085.821996230133, 5044.672096313728, 5063.569596584295, 5063.74681265937, 5076.36545431821, 5096.745162741867, 4989.3513843200335, 5002.799888004475, 5045.167547354682, 5087.771050302746, 5126.512981266774, 4544.227481328182, 4548.476925153616, 4575.313702944552, 4578.702384345007, 4613.861584152481, 6240.875182496325, 6287.371262873722, 6303.810885673437, 6333.461655759266, 6343.714532846033, 5826.785821387845, 5851.678157701324, 5854.238948936653, 5854.7072646367515, 5865.677387743673, 6595.8021259362295, 6638.900416493757, 6669.799906002828, 6689.297623749485, 6708.83227919301, 4922.483139270396, 4939.950600493985, 4946.653734238605, 4954.232094025432, 4971.7514124294, 6996.755113571052, 7035.929640703579, 7062.187848397397, 7104.751333703347, 7109.6445177741425, 5017.523335283142, 5056.165732654071, 5060.696358218445, 5077.416097148801, 5088.516590923857, 5102.515261050195, 5130.7947682093045, 5163.870903227449, 5165.560927321145, 5170.741462926839, 5762.567807414377, 5798.739056742456, 5831.183634291203, 5850.5027072699295, 5865.970670146622, 6672.733090676318, 6678.666066696645, 6683.188104564802, 6714.354983029091, 6728.629925354153, 1690.2224976510697, 1726.1369315342329, 1747.1788259518007, 1766.9823301767076, 1787.7586525819108, 1207.2575366149931, 1229.1211783574786, 1229.6434034130182, 1249.1505776072177, 1260.4706023470164, 5876.41235876409, 5909.645421274738, 5934.673592952429, 5973.611715238582, 5975.163477113208, 6598.307177746364, 6652.467802575782, 6772.796816095525, 6898.620575868275, 6968.721251149897, 5945.524358051345, 5956.16613674086, 5971.701414929236, 5989.131575921467, 5994.850128746729, 3654.5564502021552, 3670.0457880951144, 3670.541182352206, 3684.299983003221, 3707.1658876753054, 6631.336866313427, 6661.0, 6661.433778128913, 6688.163979502562, 6691.698873550702, 7161.283871612797, 7161.9973203751515, 7168.964155179191, 7187.748428804966, 7212.60330681807, 6533.183352081085, 6573.0, 6639.86720265592, 6692.765753198712, 6708.865822683519, 6902.689378978059, 6933.791986240421, 6971.372576468142, 6972.755953541602, 6988.40598549129, 6778.288684526229, 6812.455003599799, 6818.352256535622, 6838.7264509419065, 6871.896921546281, 6642.667866606747, 6646.7341306349135, 6656.633885136171, 6667.333266673294, 6692.665366731741, 4985.501449855131, 5031.773570189277, 5061.974690126526, 5062.746862656779, 5092.9745351273, 6544.574602651003, 6846.418054465426, 6883.760923033902, 6919.37725604698, 6999.00528349418, 6356.5232607555545, 6476.384743449365, 6492.740290388331, 6529.379708927644, 6582.868342633216, 6522.5760325578685, 6559.409653131241, 6600.174978127734, 6604.9009264862025, 6717.2946840581035, 6370.203724534434, 6442.838929026624, 6456.7740129095255, 6501.284858665637, 6573.408393244631, 6638.900416493853, 6682.63245521501, 6692.427279589196, 6764.830879227862, 6773.0, 6408.231012278557, 6451.354864513699, 6470.255920569007, 6513.381228783258, 6535.470845743245], "value": 5155.409941073668, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [915.0757734687963, 916.7433118726757, 934.5233930695343, 935.2611436964798, 939.1219210038614, 18959.905200473997, 19613.901930490363, 19791.208351665933, 19814.306499272545, 21790.45523861904, 20931.790682093175, 21205.893970530164, 21474.677879831816, 21588.460288492755, 21609.67585486219, 979.480875136179, 994.4033579852068, 1001.739547717595, 1005.7334806276342, 1006.0291818395248, 952.6237136331144, 969.403816652757, 969.6993931881104, 971.1890571372912, 973.8198433289829, 1044.2272718188524, 1048.811213981483, 1051.8369652703843, 1052.6894566103017, 1052.9631462898801, 1019.6635110413546, 1028.3778314119925, 1030.2118879057489, 1037.1858091398292, 1042.135027926819, 761.6420282467232, 768.2854944901239, 769.3998681028751, 771.020803579451, 772.7488566215947, 17728.645427091516, 18476.722849157275, 18899.0, 18913.7162942556, 19057.904710476498, 20565.794342056688, 20786.688199677017, 21165.25921592737, 21195.89402052995, 21361.145554177816, 17037.91481042599, 17389.91305043467, 17469.0, 17563.5609109772, 17569.912150439293, 3770.1140232045445, 3795.411711184771, 3797.791121488317, 3802.315583195024, 3809.7904615246152, 129690.0, 130211.0, 130272.3486382562, 133035.33482332528, 134317.0, 16746.0, 16815.831841681673, 17112.65774684524, 17136.143192840307, 17302.307907683553, 29921.251968700955, 30699.0, 30718.232044198627, 30765.38469230603, 31069.0, 8112.959435202904, 8286.0056793184, 8385.454945428528, 8477.660893564313, 8835.64657413709, 4150.626443620089, 4237.724547904377, 4251.5748425157235, 4261.786910654455, 4271.1671224111, 5426.647267927572, 5566.582506311962, 5584.413636568183, 5897.144913987534, 6028.125921741323, 1164.3596022187762, 1183.2545496337252, 1200.099925056208, 1203.2780331801025, 1205.626255860686, 697.1007400453358, 701.4283359062348, 712.4656507619286, 712.9643517824088, 713.3722324354535, 12249.816252756216, 12621.873781262253, 12664.81002784959, 13037.217766934014, 13258.602241932758, 691.19475810681, 708.6704682322745, 713.169157931015, 713.3864876206459, 723.7611588175889, 5499.807506737325, 5581.804636837691, 5832.212651292021, 6406.007068904327, 6451.935480645134, 100483.0, 108143.0, 108509.91524068492, 108762.45618771855, 116901.0, 93545.5322723382, 94257.52871235737, 96752.51623741977, 97230.51384743172, 97455.51272243736, 86038.56980715056, 87672.56163719141, 87749.12250877409, 90728.5463572678, 91551.0, 12600.495980160691, 12968.416421261254, 13008.544700935421, 13209.405576749261, 13310.534131305358, 40047.599524004385, 40323.79838100791, 40358.39462408125, 41570.0, 41855.0, 71793.0, 73281.6335918317, 73460.63269683617, 73925.0, 83634.0, 19320.0, 19892.403227903185, 19914.90042549778, 19994.500137496678, 20101.59796804085, 22217.111315547198, 22497.0, 23329.3001209964, 23620.645690314315, 23668.881655591613, 19196.0, 19561.0, 20065.498362541053, 21082.78917210808, 21225.36323910285, 20149.6977545334, 20995.160193592696, 21038.580563235424, 21054.47363815917, 21250.36248912535, 19154.138063786886, 19291.903540482206, 19662.0, 20071.29750458727, 20109.49726256797, 12598.937005314914, 13103.541376051793, 13875.306234688427, 14028.29858507091, 14131.576052718436, 14076.085054471427, 14391.784123238369, 14511.927440362731, 14525.273736313353, 15340.693186135992], "value": 9479.221231682715, "unit": "score"}]}, {"extraOptions": ["e10s", "stylo_disabled"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1489.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 115.0, 103.0, 108.0, 108.0, 108.0, 109.0, 99.0, 107.0, 108.0], "value": 106.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 119.0, 151.0, 179.0, 117.0, 146.0, 151.0, 122.0, 174.0, 119.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 161.0, 162.0, 162.0, 161.0, 164.0, 165.0, 167.0, 163.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 174.0, 76.0, 74.0, 76.0, 74.0, 149.0, 76.0], "value": 92.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 131.0, 78.0, 79.0, 80.0, 130.0, 79.0, 80.0, 128.0], "value": 94.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 115.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 149.0, 147.0, 148.0, 148.0, 150.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 94.0, 96.0, 95.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 51.0, 51.0, 52.0, 51.0, 52.0, 51.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 37.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 76.0, 74.0, 77.0, 76.0, 77.0, 75.0, 77.0, 76.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 129.0, 120.0, 118.0, 123.0, 135.0, 123.0, 121.0, 119.0, 163.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 172.0, 173.0, 180.0, 169.0, 184.0, 174.0, 168.0, 168.0, 179.0], "value": 174.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 64.0, 67.0, 63.0, 65.0, 66.0, 66.0, 66.0, 66.0, 64.0], "value": 65.9, "unit": "score"}]}]} 11:47:13 INFO - Return code: 0 11:47:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:47:13 INFO - # TBPL SUCCESS # 11:47:13 INFO - Running post-action listener: _package_coverage_data 11:47:13 INFO - Running post-action listener: _resource_record_post_action 11:47:13 INFO - [mozharness: 2017-09-06 18:47:13.470288Z] Finished run-tests step (success) 11:47:13 INFO - Running post-run listener: _resource_record_post_run 11:47:13 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 390074368; Read time: 16; Write time: 231636 11:47:13 INFO - TinderboxPrint: CPU usage
13.1% 11:47:13 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 11:47:13 INFO - TinderboxPrint: I/O write bytes / time
390,074,368 / 231,636 11:47:13 INFO - TinderboxPrint: CPU idle
8,221.1 (86.9%) 11:47:13 INFO - TinderboxPrint: CPU user
1,190.4 (12.6%) 11:47:13 INFO - TinderboxPrint: Swap in / out
0 / 0 11:47:13 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 106651648; Read time: 0; Write time: 91452 11:47:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:47:13 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283418624; Read time: 16; Write time: 140164 11:47:14 INFO - Running post-run listener: _upload_blobber_files 11:47:14 INFO - Blob upload gear active. 11:47:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:47:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:47:14 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'] 11:47:14 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 11:47:14 INFO - (blobuploader) - INFO - Open directory for files ... 11:47:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log ... 11:47:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_raw.log: uploaded 11:47:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:15 INFO - (blobuploader) - INFO - Done attempting. 11:47:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:47:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:47:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:16 INFO - (blobuploader) - INFO - Done attempting. 11:47:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:47:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:47:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:23 INFO - (blobuploader) - INFO - Done attempting. 11:47:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log ... 11:47:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_errorsummary.log: uploaded 11:47:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:24 INFO - (blobuploader) - INFO - Done attempting. 11:47:24 INFO - (blobuploader) - INFO - Iteration through files over. 11:47:24 INFO - Return code: 0 11:47:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:47:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:47:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d284f433cabc3864d40696967be98991f04fbfbd5316554664497b9c298866883d2e4580f99bac9868c93d1d7fd0bca63ed51573cf0b0427680a1827b7c281e9", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4c5c1754285c5ce25626112141ff9477853a65ec924d799d270ca1b4d427c0015e5ae7b66ae11e712b2466275c0e94a75019f63b01e14c2f3dcd0e9fcb951508", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b76ccb174e6ed918861e47f51c0be007adc0c984592a2ad20495d9cd1521b9c86ed2812519d732246a75cff65b598f7520dd76f8275ddf38983d7a895e963a3c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7078bfed37c8dfcf1676bc932254f71b26479ac0d55776e144e23e5d4864b6ebef2e3c3f279a844a81589411833883602d580ca4edea7d86bd51d7bf9e983332"} 11:47:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:47:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:47:24 INFO - Contents: 11:47:24 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d284f433cabc3864d40696967be98991f04fbfbd5316554664497b9c298866883d2e4580f99bac9868c93d1d7fd0bca63ed51573cf0b0427680a1827b7c281e9", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4c5c1754285c5ce25626112141ff9477853a65ec924d799d270ca1b4d427c0015e5ae7b66ae11e712b2466275c0e94a75019f63b01e14c2f3dcd0e9fcb951508", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b76ccb174e6ed918861e47f51c0be007adc0c984592a2ad20495d9cd1521b9c86ed2812519d732246a75cff65b598f7520dd76f8275ddf38983d7a895e963a3c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7078bfed37c8dfcf1676bc932254f71b26479ac0d55776e144e23e5d4864b6ebef2e3c3f279a844a81589411833883602d580ca4edea7d86bd51d7bf9e983332"} 11:47:24 INFO - Running post-run listener: copy_logs_to_upload_dir 11:47:24 INFO - Copying logs to upload dir... 11:47:24 INFO - mkdir: /builds/slave/test/build/upload/logs 11:47:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.182966 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-06 11:47:24.164748) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 11:47:24.170778) ========= 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=d2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814 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/d284f433cabc3864d40696967be98991f04fbfbd5316554664497b9c298866883d2e4580f99bac9868c93d1d7fd0bca63ed51573cf0b0427680a1827b7c281e9", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4c5c1754285c5ce25626112141ff9477853a65ec924d799d270ca1b4d427c0015e5ae7b66ae11e712b2466275c0e94a75019f63b01e14c2f3dcd0e9fcb951508", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b76ccb174e6ed918861e47f51c0be007adc0c984592a2ad20495d9cd1521b9c86ed2812519d732246a75cff65b598f7520dd76f8275ddf38983d7a895e963a3c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7078bfed37c8dfcf1676bc932254f71b26479ac0d55776e144e23e5d4864b6ebef2e3c3f279a844a81589411833883602d580ca4edea7d86bd51d7bf9e983332"} build_url:https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011510 build_url: 'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d284f433cabc3864d40696967be98991f04fbfbd5316554664497b9c298866883d2e4580f99bac9868c93d1d7fd0bca63ed51573cf0b0427680a1827b7c281e9", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4c5c1754285c5ce25626112141ff9477853a65ec924d799d270ca1b4d427c0015e5ae7b66ae11e712b2466275c0e94a75019f63b01e14c2f3dcd0e9fcb951508", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b76ccb174e6ed918861e47f51c0be007adc0c984592a2ad20495d9cd1521b9c86ed2812519d732246a75cff65b598f7520dd76f8275ddf38983d7a895e963a3c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7078bfed37c8dfcf1676bc932254f71b26479ac0d55776e144e23e5d4864b6ebef2e3c3f279a844a81589411833883602d580ca4edea7d86bd51d7bf9e983332"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 11:47:24.199559) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:47:24.199890) ========= 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=d2a8c569965ab8367867b893000001a1-1504722314.343654-1251135814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004493 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:47:24.282288) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 11:47:24.282608) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 11:47:24.282897) ========= ========= Total master_lag: 0.20 =========