builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-027 starttime: 1504258104.66 results: success (0) revision: a3585c77e2b1bc5f5fea907e97762f7b47a12033 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:24.660560) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:24.661015) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:24.661301) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:24.747337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:24.747626) ========= 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=1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 02:28:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 52.5M=0s 2017-09-01 02:28:24 (52.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151339 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:24.932178) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:24.932463) ========= 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=1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015937 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:25.029193) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-01 02:28:25.029540) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a3585c77e2b1bc5f5fea907e97762f7b47a12033 --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 a3585c77e2b1bc5f5fea907e97762f7b47a12033 --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=1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 02:28:25,077 truncating revision to first 12 chars 2017-09-01 02:28:25,078 Setting DEBUG logging. 2017-09-01 02:28:25,078 attempt 1/10 2017-09-01 02:28:25,078 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a3585c77e2b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 02:28:25,452 attempt 1/10 2017-09-01 02:28:26,120 current task status: no status available at this point. state: STARTED 2017-09-01 02:28:26,120 sleeping for 11.00s (attempt 1/10) 2017-09-01 02:28:37,131 attempt 2/10 2017-09-01 02:28:37,466 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-01 02:28:37,672 unpacking tar archive at: mozilla-central-a3585c77e2b1/testing/mozharness/ program finished with exit code 0 elapsedTime=12.947440 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-01 02:28:37.997967) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:37.998275) ========= script_repo_revision: a3585c77e2b1bc5f5fea907e97762f7b47a12033 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:37.998648) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:37.998924) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:38.010794) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-01 02:28:38.011222) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:28:38 INFO - MultiFileLogger online at 20170901 02:28:38 in /builds/slave/test 02:28:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:28:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:28:38 INFO - {'append_to_log': False, 02:28:38 INFO - 'base_work_dir': '/builds/slave/test', 02:28:38 INFO - 'blob_upload_branch': 'Firefox', 02:28:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:28:38 INFO - 'branch': 'Firefox', 02:28:38 INFO - 'buildbot_json_path': 'buildprops.json', 02:28:38 INFO - 'code_coverage': False, 02:28:38 INFO - 'config_files': ('talos/linux_config.py',), 02:28:38 INFO - 'default_actions': ('clobber', 02:28:38 INFO - 'read-buildbot-config', 02:28:38 INFO - 'download-and-extract', 02:28:38 INFO - 'populate-webroot', 02:28:38 INFO - 'create-virtualenv', 02:28:38 INFO - 'install', 02:28:38 INFO - 'setup-mitmproxy', 02:28:38 INFO - 'run-tests'), 02:28:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:28:38 INFO - 'disable_ccov_upload': False, 02:28:38 INFO - 'disable_stylo': False, 02:28:38 INFO - 'download_minidump_stackwalk': True, 02:28:38 INFO - 'download_symbols': 'ondemand', 02:28:38 INFO - 'enable_stylo': False, 02:28:38 INFO - 'enable_webrender': False, 02:28:38 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:28:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:28:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:28:38 INFO - 'gecko_profile': False, 02:28:38 INFO - 'gecko_profile_interval': 0, 02:28:38 INFO - 'installer_path': 'installer.exe', 02:28:38 INFO - 'log_level': 'info', 02:28:38 INFO - 'log_name': 'talos', 02:28:38 INFO - 'log_to_console': True, 02:28:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:28:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:28:38 INFO - 'opt_config_files': (), 02:28:38 INFO - 'pip_index': False, 02:28:38 INFO - 'suite': 'dromaeojs-e10s', 02:28:38 INFO - 'system_bits': '32', 02:28:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:28:38 INFO - 'title': 'talos-linux64-ix-027', 02:28:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:28:38 INFO - 'use_talos_json': True, 02:28:38 INFO - 'verify': 'False', 02:28:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:28:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:28:38 INFO - 'work_dir': 'build'} 02:28:38 INFO - [mozharness: 2017-09-01 09:28:38.198170Z] Running clobber step. 02:28:38 INFO - Running pre-action listener: _resource_record_pre_action 02:28:38 INFO - Running main action method: clobber 02:28:38 INFO - rmtree: /builds/slave/test/build 02:28:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:28:39 INFO - Running post-action listener: _resource_record_post_action 02:28:39 INFO - [mozharness: 2017-09-01 09:28:39.223138Z] Finished clobber step (success) 02:28:39 INFO - [mozharness: 2017-09-01 09:28:39.223236Z] Running read-buildbot-config step. 02:28:39 INFO - Running pre-action listener: _resource_record_pre_action 02:28:39 INFO - Running main action method: read_buildbot_config 02:28:39 INFO - Using buildbot properties: 02:28:39 INFO - { 02:28:39 INFO - "project": "", 02:28:39 INFO - "product": "firefox", 02:28:39 INFO - "who": "archaeopteryx@coole-files.de", 02:28:39 INFO - "installer_path": "public/build/target.tar.bz2", 02:28:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 02:28:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:28:39 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 02:28:39 INFO - "stage_platform": "linux64-stylo", 02:28:39 INFO - "basedir": "/builds/slave/test", 02:28:39 INFO - "buildnumber": 113, 02:28:39 INFO - "platform": "ubuntu64_hw_stylo", 02:28:39 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:28:39 INFO - "slavebuilddir": "test", 02:28:39 INFO - "taskId": "WbhbLYcURTiKbtmw0KT8sg", 02:28:39 INFO - "branch": "mozilla-central", 02:28:39 INFO - "script_repo_revision": "production", 02:28:39 INFO - "revision": "a3585c77e2b1bc5f5fea907e97762f7b47a12033", 02:28:39 INFO - "slavename": "talos-linux64-ix-027", 02:28:39 INFO - "repo_path": "mozilla-central" 02:28:39 INFO - } 02:28:39 INFO - Finding installer, test and symbols from parent task. 02:28:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WbhbLYcURTiKbtmw0KT8sg'}, attempt #1 02:28:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WbhbLYcURTiKbtmw0KT8sg'}, attempt #1 02:28:40 INFO - Task dependencies: QjwniFnmR7exVsWnUkO_3Q 02:28:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q'}, attempt #1 02:28:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:28:40 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.test_packages.json 02:28:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip 02:28:40 INFO - Running post-action listener: _resource_record_post_action 02:28:40 INFO - [mozharness: 2017-09-01 09:28:40.569920Z] Finished read-buildbot-config step (success) 02:28:40 INFO - [mozharness: 2017-09-01 09:28:40.570137Z] Running download-and-extract step. 02:28:40 INFO - Running pre-action listener: _resource_record_pre_action 02:28:40 INFO - Running main action method: download_and_extract 02:28:40 INFO - mkdir: /builds/slave/test/build/tests 02:28:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:40 INFO - trying https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.test_packages.json 02:28:40 INFO - Downloading https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:28:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:28:41 INFO - Downloaded 989 bytes. 02:28:41 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:28:41 INFO - Using the following test package requirements: 02:28:41 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:28:41 INFO - u'common': [u'target.common.tests.zip'], 02:28:41 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:28:41 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:28:41 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:28:41 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:28:41 INFO - u'mozbase': [u'target.common.tests.zip'], 02:28:41 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:28:41 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:28:41 INFO - u'web-platform': [u'target.common.tests.zip', 02:28:41 INFO - u'target.web-platform.tests.tar.gz'], 02:28:41 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:28:41 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:28:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip 02:28:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:28:41 INFO - Fetch https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip into memory 02:28:42 INFO - Content-Length response header: 54957508 02:28:42 INFO - Bytes received: 54957508 02:28:53 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:28:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip 02:28:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:28:53 INFO - Fetch https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip into memory 02:28:54 INFO - Content-Length response header: 54957508 02:28:55 INFO - Bytes received: 54957508 02:29:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.talos.tests.zip 02:29:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:29:08 INFO - Fetch https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.talos.tests.zip into memory 02:29:08 INFO - Content-Length response header: 14496650 02:29:08 INFO - Bytes received: 14496650 02:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:09 INFO - trying https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:29:09 INFO - Downloading https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:29:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:29:10 INFO - Downloaded 62582204 bytes. 02:29:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:29:10 INFO - mkdir: /builds/slave/test/properties 02:29:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:29:10 INFO - Writing to file /builds/slave/test/properties/build_url 02:29:10 INFO - Contents: 02:29:10 INFO - build_url:https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:29:10 INFO - Running post-action listener: _resource_record_post_action 02:29:10 INFO - Running post-action listener: find_tests_for_verification 02:29:10 INFO - Running post-action listener: set_extra_try_arguments 02:29:10 INFO - [mozharness: 2017-09-01 09:29:10.114667Z] Finished download-and-extract step (success) 02:29:10 INFO - [mozharness: 2017-09-01 09:29:10.114763Z] Running populate-webroot step. 02:29:10 INFO - Running pre-action listener: _resource_record_pre_action 02:29:10 INFO - Running main action method: populate_webroot 02:29:10 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:29:10 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:29:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:29:10 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:29:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:29:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:10 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:10 INFO - 'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:29:10 INFO - 'tests': ['damp', 'tps']}, 02:29:10 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:10 INFO - 'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['damp', 'tps']}, 02:29:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:29:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:29:10 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:29:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:29:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:29:10 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:10 INFO - 'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:29:10 INFO - 'other-e10s': {'tests': ['a11yr', 02:29:10 INFO - 'ts_paint', 02:29:10 INFO - 'tpaint', 02:29:10 INFO - 'sessionrestore', 02:29:10 INFO - 'sessionrestore_many_windows', 02:29:10 INFO - 'sessionrestore_no_auto_restore', 02:29:10 INFO - 'tabpaint']}, 02:29:10 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['a11yr', 02:29:10 INFO - 'ts_paint', 02:29:10 INFO - 'tpaint', 02:29:10 INFO - 'sessionrestore', 02:29:10 INFO - 'sessionrestore_many_windows', 02:29:10 INFO - 'sessionrestore_no_auto_restore', 02:29:10 INFO - 'tabpaint']}, 02:29:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:29:10 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:29:10 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['bloom_basic_singleton']}, 02:29:10 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['bloom_basic']}, 02:29:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:29:10 INFO - 'tsvgr_opacity', 02:29:10 INFO - 'tart', 02:29:10 INFO - 'tscrollx', 02:29:10 INFO - 'tsvg_static']}, 02:29:10 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['tsvgx', 02:29:10 INFO - 'tsvgr_opacity', 02:29:10 INFO - 'tart', 02:29:10 INFO - 'tscrollx', 02:29:10 INFO - 'tsvg_static']}, 02:29:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:29:10 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:10 INFO - 'talos_options': ['--enable-stylo'], 02:29:10 INFO - 'tests': ['tp5o']}, 02:29:10 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:29:10 INFO - 'talos_options': ['--mitmproxy', 02:29:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:29:10 INFO - '--firstNonBlankPaint'], 02:29:10 INFO - 'tests': ['tp6_google', 02:29:10 INFO - 'tp6_youtube', 02:29:10 INFO - 'tp6_amazon', 02:29:10 INFO - 'tp6_facebook']}, 02:29:10 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:29:10 INFO - 'talos_options': ['--enable-stylo', 02:29:10 INFO - '--mitmproxy', 02:29:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:29:10 INFO - '--firstNonBlankPaint'], 02:29:10 INFO - 'tests': ['tp6_google', 02:29:10 INFO - 'tp6_youtube', 02:29:10 INFO - 'tp6_amazon', 02:29:10 INFO - 'tp6_facebook']}, 02:29:10 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:29:10 INFO - 'talos_options': ['--enable-stylo', 02:29:10 INFO - '--stylo-threads=1', 02:29:10 INFO - '--mitmproxy', 02:29:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:29:10 INFO - '--firstNonBlankPaint'], 02:29:10 INFO - 'tests': ['tp6_google', 02:29:10 INFO - 'tp6_youtube', 02:29:10 INFO - 'tp6_amazon', 02:29:10 INFO - 'tp6_facebook']}, 02:29:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:29:10 INFO - 'talos_options': ['--xperf_path', 02:29:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:10 INFO - 'tests': ['tp5n']}, 02:29:10 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:10 INFO - 'talos_options': ['--enable-stylo', 02:29:10 INFO - '--xperf_path', 02:29:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:10 INFO - 'tests': ['tp5n']}}} 02:29:10 INFO - Running post-action listener: _resource_record_post_action 02:29:10 INFO - [mozharness: 2017-09-01 09:29:10.129172Z] Finished populate-webroot step (success) 02:29:10 INFO - [mozharness: 2017-09-01 09:29:10.129261Z] Running create-virtualenv step. 02:29:10 INFO - Running pre-action listener: _resource_record_pre_action 02:29:10 INFO - Running main action method: create_virtualenv 02:29:10 INFO - Creating virtualenv /builds/slave/test/build/venv 02:29:10 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:29:10 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:29:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:29:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:29:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:29:10 INFO - Installing distribute.............................................................................................................................................................................................done. 02:29:11 INFO - Installing pip...............done. 02:29:11 INFO - Return code: 0 02:29:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:29:11 INFO - Reading from file tmpfile_stdout 02:29:11 INFO - Output received: 02:29:11 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:29:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:11 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 0x21e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, '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': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:29:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:29:11 INFO - Using env: {'DISPLAY': ':0', 02:29:11 INFO - 'HOME': '/home/cltbld', 02:29:11 INFO - 'LANG': 'en_US.UTF-8', 02:29:11 INFO - 'LANGUAGE': 'en_US:en', 02:29:11 INFO - 'LOGNAME': 'cltbld', 02:29:11 INFO - 'MAIL': '/var/mail/cltbld', 02:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:11 INFO - 'MOZ_NO_REMOTE': '1', 02:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:11 INFO - 'NO_EM_RESTART': '1', 02:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:11 INFO - 'PWD': '/builds/slave/test', 02:29:11 INFO - 'SHELL': '/bin/bash', 02:29:11 INFO - 'SHLVL': '1', 02:29:11 INFO - 'TERM': 'linux', 02:29:11 INFO - 'TMOUT': '86400', 02:29:11 INFO - 'USER': 'cltbld', 02:29:11 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:11 INFO - '_': '/tools/buildbot/bin/python'} 02:29:12 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:29:12 INFO - Downloading/unpacking pip>=1.5 02:29:12 INFO - Running setup.py egg_info for package pip 02:29:12 INFO - warning: no files found matching 'pip/cacert.pem' 02:29:12 INFO - warning: no files found matching '*.html' under directory 'docs' 02:29:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:29:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:29:12 INFO - Installing collected packages: pip 02:29:12 INFO - Found existing installation: pip 1.1 02:29:12 INFO - Uninstalling pip: 02:29:12 INFO - Successfully uninstalled pip 02:29:12 INFO - Running setup.py install for pip 02:29:12 INFO - warning: no files found matching 'pip/cacert.pem' 02:29:12 INFO - warning: no files found matching '*.html' under directory 'docs' 02:29:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:29:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:29:12 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Successfully installed pip 02:29:12 INFO - Cleaning up... 02:29:12 INFO - Return code: 0 02:29:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29: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')]} 02:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29: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', '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 0x21e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, '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': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:29: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:29: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 psutil>=3.1.1 02:29:12 INFO - Using env: {'DISPLAY': ':0', 02:29:12 INFO - 'HOME': '/home/cltbld', 02:29:12 INFO - 'LANG': 'en_US.UTF-8', 02:29:12 INFO - 'LANGUAGE': 'en_US:en', 02:29:12 INFO - 'LOGNAME': 'cltbld', 02:29:12 INFO - 'MAIL': '/var/mail/cltbld', 02:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:12 INFO - 'MOZ_NO_REMOTE': '1', 02:29:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:12 INFO - 'NO_EM_RESTART': '1', 02:29:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:12 INFO - 'PWD': '/builds/slave/test', 02:29:12 INFO - 'SHELL': '/bin/bash', 02:29:12 INFO - 'SHLVL': '1', 02:29:12 INFO - 'TERM': 'linux', 02:29:12 INFO - 'TMOUT': '86400', 02:29:12 INFO - 'USER': 'cltbld', 02:29:12 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:12 INFO - '_': '/tools/buildbot/bin/python'} 02:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:12 INFO - Downloading/unpacking psutil>=3.1.1 02:29: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 02:29: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 02:29: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 02:29: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 02:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:29:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:13 INFO - Installing collected packages: psutil 02:29:13 INFO - Running setup.py install for psutil 02:29:13 INFO - building 'psutil._psutil_linux' extension 02:29:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:29:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:29:13 INFO - building 'psutil._psutil_posix' extension 02:29:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 02:29:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 02:29:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:14 INFO - Successfully installed psutil 02:29:14 INFO - Cleaning up... 02:29:14 INFO - Return code: 0 02:29:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29: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')]} 02:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, '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': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:29: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:29: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 mozsystemmonitor==0.3 02:29:14 INFO - Using env: {'DISPLAY': ':0', 02:29:14 INFO - 'HOME': '/home/cltbld', 02:29:14 INFO - 'LANG': 'en_US.UTF-8', 02:29:14 INFO - 'LANGUAGE': 'en_US:en', 02:29:14 INFO - 'LOGNAME': 'cltbld', 02:29:14 INFO - 'MAIL': '/var/mail/cltbld', 02:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:14 INFO - 'MOZ_NO_REMOTE': '1', 02:29:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:14 INFO - 'NO_EM_RESTART': '1', 02:29:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:14 INFO - 'PWD': '/builds/slave/test', 02:29:14 INFO - 'SHELL': '/bin/bash', 02:29:14 INFO - 'SHLVL': '1', 02:29:14 INFO - 'TERM': 'linux', 02:29:14 INFO - 'TMOUT': '86400', 02:29:14 INFO - 'USER': 'cltbld', 02:29:14 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:14 INFO - '_': '/tools/buildbot/bin/python'} 02:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:29: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 02:29: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 02:29: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 02:29: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 02:29:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:29:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:29:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:29:15 INFO - Installing collected packages: mozsystemmonitor 02:29:15 INFO - Running setup.py install for mozsystemmonitor 02:29:15 INFO - Successfully installed mozsystemmonitor 02:29:15 INFO - Cleaning up... 02:29:15 INFO - Return code: 0 02:29:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:15 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 0x21e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, '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': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:29:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:29:15 INFO - Using env: {'DISPLAY': ':0', 02:29:15 INFO - 'HOME': '/home/cltbld', 02:29:15 INFO - 'LANG': 'en_US.UTF-8', 02:29:15 INFO - 'LANGUAGE': 'en_US:en', 02:29:15 INFO - 'LOGNAME': 'cltbld', 02:29:15 INFO - 'MAIL': '/var/mail/cltbld', 02:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:15 INFO - 'MOZ_NO_REMOTE': '1', 02:29:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:15 INFO - 'NO_EM_RESTART': '1', 02:29:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:15 INFO - 'PWD': '/builds/slave/test', 02:29:15 INFO - 'SHELL': '/bin/bash', 02:29:15 INFO - 'SHLVL': '1', 02:29:15 INFO - 'TERM': 'linux', 02:29:15 INFO - 'TMOUT': '86400', 02:29:15 INFO - 'USER': 'cltbld', 02:29:15 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:15 INFO - '_': '/tools/buildbot/bin/python'} 02:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:15 INFO - Downloading/unpacking jsonschema==2.5.1 02:29: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 02:29: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 02:29: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 02:29: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 02:29:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:17 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:17 INFO - warning: no files found matching '*.txt' 02:29:17 INFO - no previously-included directories found matching 'build' 02:29:17 INFO - no previously-included directories found matching 'dist' 02:29:17 INFO - no previously-included directories found matching '.git*' 02:29:17 INFO - Installing collected packages: jsonschema, functools32 02:29:17 INFO - Running setup.py install for functools32 02:29:17 INFO - warning: no files found matching '*.txt' 02:29:17 INFO - no previously-included directories found matching 'build' 02:29:17 INFO - no previously-included directories found matching 'dist' 02:29:17 INFO - no previously-included directories found matching '.git*' 02:29:17 INFO - Successfully installed jsonschema functools32 02:29:17 INFO - Cleaning up... 02:29:17 INFO - Return code: 0 02:29:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:17 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 0x21e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, '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': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:29:17 INFO - Using env: {'DISPLAY': ':0', 02:29:17 INFO - 'HOME': '/home/cltbld', 02:29:17 INFO - 'LANG': 'en_US.UTF-8', 02:29:17 INFO - 'LANGUAGE': 'en_US:en', 02:29:17 INFO - 'LOGNAME': 'cltbld', 02:29:17 INFO - 'MAIL': '/var/mail/cltbld', 02:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:17 INFO - 'MOZ_NO_REMOTE': '1', 02:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:17 INFO - 'NO_EM_RESTART': '1', 02:29:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:17 INFO - 'PWD': '/builds/slave/test', 02:29:17 INFO - 'SHELL': '/bin/bash', 02:29:17 INFO - 'SHLVL': '1', 02:29:17 INFO - 'TERM': 'linux', 02:29:17 INFO - 'TMOUT': '86400', 02:29:17 INFO - 'USER': 'cltbld', 02:29:17 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:17 INFO - '_': '/tools/buildbot/bin/python'} 02:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:29:17 INFO - Cleaning up... 02:29:17 INFO - Return code: 0 02:29:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:17 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 0x21e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, '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': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:29:17 INFO - Using env: {'DISPLAY': ':0', 02:29:17 INFO - 'HOME': '/home/cltbld', 02:29:17 INFO - 'LANG': 'en_US.UTF-8', 02:29:17 INFO - 'LANGUAGE': 'en_US:en', 02:29:17 INFO - 'LOGNAME': 'cltbld', 02:29:17 INFO - 'MAIL': '/var/mail/cltbld', 02:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:17 INFO - 'MOZ_NO_REMOTE': '1', 02:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:17 INFO - 'NO_EM_RESTART': '1', 02:29:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:17 INFO - 'PWD': '/builds/slave/test', 02:29:17 INFO - 'SHELL': '/bin/bash', 02:29:17 INFO - 'SHLVL': '1', 02:29:17 INFO - 'TERM': 'linux', 02:29:17 INFO - 'TMOUT': '86400', 02:29:17 INFO - 'USER': 'cltbld', 02:29:17 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:17 INFO - '_': '/tools/buildbot/bin/python'} 02:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:17 INFO - Downloading/unpacking blobuploader==1.2.4 02:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:18 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:19 INFO - Downloading docopt-0.6.1.tar.gz 02:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:19 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:19 INFO - Running setup.py install for blobuploader 02:29:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:19 INFO - Running setup.py install for requests 02:29:20 INFO - Running setup.py install for docopt 02:29:20 INFO - Successfully installed blobuploader requests docopt 02:29:20 INFO - Cleaning up... 02:29:20 INFO - Return code: 0 02:29:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:20 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 0x21e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, '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': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:20 INFO - Using env: {'DISPLAY': ':0', 02:29:20 INFO - 'HOME': '/home/cltbld', 02:29:20 INFO - 'LANG': 'en_US.UTF-8', 02:29:20 INFO - 'LANGUAGE': 'en_US:en', 02:29:20 INFO - 'LOGNAME': 'cltbld', 02:29:20 INFO - 'MAIL': '/var/mail/cltbld', 02:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:20 INFO - 'MOZ_NO_REMOTE': '1', 02:29:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:20 INFO - 'NO_EM_RESTART': '1', 02:29:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:20 INFO - 'PWD': '/builds/slave/test', 02:29:20 INFO - 'SHELL': '/bin/bash', 02:29:20 INFO - 'SHLVL': '1', 02:29:20 INFO - 'TERM': 'linux', 02:29:20 INFO - 'TMOUT': '86400', 02:29:20 INFO - 'USER': 'cltbld', 02:29:20 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:20 INFO - '_': '/tools/buildbot/bin/python'} 02:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:20 INFO - Running setup.py (path:/tmp/pip-GwsBvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:20 INFO - Running setup.py (path:/tmp/pip-4hlPTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:20 INFO - Running setup.py (path:/tmp/pip-h8pjfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:20 INFO - Running setup.py (path:/tmp/pip-qw0uQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:20 INFO - Running setup.py (path:/tmp/pip-ZLRB_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:20 INFO - Running setup.py (path:/tmp/pip-C5zilm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:20 INFO - Running setup.py (path:/tmp/pip-nmhGN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:20 INFO - Running setup.py (path:/tmp/pip-YDaIYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:20 INFO - Running setup.py (path:/tmp/pip-CJVfCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:20 INFO - Running setup.py (path:/tmp/pip-fDPjuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:21 INFO - Running setup.py (path:/tmp/pip-evrCbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:21 INFO - Running setup.py (path:/tmp/pip-chaUUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:21 INFO - Running setup.py (path:/tmp/pip-yCC7QT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:21 INFO - Running setup.py (path:/tmp/pip-3kR2d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:21 INFO - Running setup.py (path:/tmp/pip-TBbSCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:21 INFO - Running setup.py (path:/tmp/pip-yH1VxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:21 INFO - Running setup.py (path:/tmp/pip-vAhDPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:21 INFO - Running setup.py install for manifestparser 02:29:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:21 INFO - Running setup.py install for mozcrash 02:29:21 INFO - Running setup.py install for mozdebug 02:29:21 INFO - Running setup.py install for mozdevice 02:29:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:21 INFO - Running setup.py install for mozfile 02:29:22 INFO - Running setup.py install for mozhttpd 02:29:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Running setup.py install for mozinfo 02:29:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Running setup.py install for mozInstall 02:29:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Running setup.py install for mozleak 02:29:22 INFO - Running setup.py install for mozlog 02:29:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Running setup.py install for moznetwork 02:29:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Running setup.py install for mozprocess 02:29:22 INFO - Running setup.py install for mozprofile 02:29:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Running setup.py install for mozrunner 02:29:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:22 INFO - Running setup.py install for mozscreenshot 02:29:23 INFO - Running setup.py install for moztest 02:29:23 INFO - Running setup.py install for mozversion 02:29:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:29:23 INFO - Cleaning up... 02:29:23 INFO - Return code: 0 02:29:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:23 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 0x21e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, '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': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:29:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:23 INFO - Using env: {'DISPLAY': ':0', 02:29:23 INFO - 'HOME': '/home/cltbld', 02:29:23 INFO - 'LANG': 'en_US.UTF-8', 02:29:23 INFO - 'LANGUAGE': 'en_US:en', 02:29:23 INFO - 'LOGNAME': 'cltbld', 02:29:23 INFO - 'MAIL': '/var/mail/cltbld', 02:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:23 INFO - 'MOZ_NO_REMOTE': '1', 02:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:23 INFO - 'NO_EM_RESTART': '1', 02:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:23 INFO - 'PWD': '/builds/slave/test', 02:29:23 INFO - 'SHELL': '/bin/bash', 02:29:23 INFO - 'SHLVL': '1', 02:29:23 INFO - 'TERM': 'linux', 02:29:23 INFO - 'TMOUT': '86400', 02:29:23 INFO - 'USER': 'cltbld', 02:29:23 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:23 INFO - '_': '/tools/buildbot/bin/python'} 02:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:23 INFO - Running setup.py (path:/tmp/pip-dGaRkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:23 INFO - Running setup.py (path:/tmp/pip-0iwwCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:23 INFO - Running setup.py (path:/tmp/pip-rOYtxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:23 INFO - Running setup.py (path:/tmp/pip-S1mFmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:23 INFO - Running setup.py (path:/tmp/pip-A7M7LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:23 INFO - Running setup.py (path:/tmp/pip-duLc_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:23 INFO - Running setup.py (path:/tmp/pip-zeDE2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:23 INFO - Running setup.py (path:/tmp/pip-Uxvya4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:24 INFO - Running setup.py (path:/tmp/pip-2wGhyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:24 INFO - Running setup.py (path:/tmp/pip-dAZKUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:24 INFO - Running setup.py (path:/tmp/pip-D_Hgh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:24 INFO - Running setup.py (path:/tmp/pip-Ojzg2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:24 INFO - Running setup.py (path:/tmp/pip-xJlyOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:24 INFO - Running setup.py (path:/tmp/pip-QargN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:24 INFO - Running setup.py (path:/tmp/pip-QB_TVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:24 INFO - Running setup.py (path:/tmp/pip-d9qTRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:24 INFO - Running setup.py (path:/tmp/pip-wNNYUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:29:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:25 INFO - Downloading blessings-1.6.tar.gz 02:29:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:25 INFO - Installing collected packages: blessings 02:29:25 INFO - Running setup.py install for blessings 02:29:25 INFO - Successfully installed blessings 02:29:25 INFO - Cleaning up... 02:29:26 INFO - Return code: 0 02:29:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:26 INFO - Reading from file tmpfile_stdout 02:29:26 INFO - Current package versions: 02:29:26 INFO - argparse == 1.2.1 02:29:26 INFO - blessings == 1.6 02:29:26 INFO - blobuploader == 1.2.4 02:29:26 INFO - docopt == 0.6.1 02:29:26 INFO - functools32 == 3.2.3-2 02:29:26 INFO - jsonschema == 2.5.1 02:29:26 INFO - manifestparser == 1.1 02:29:26 INFO - mozInstall == 1.13 02:29:26 INFO - mozcrash == 1.0 02:29:26 INFO - mozdebug == 0.1 02:29:26 INFO - mozdevice == 0.51 02:29:26 INFO - mozfile == 1.2 02:29:26 INFO - mozhttpd == 0.7 02:29:26 INFO - mozinfo == 0.10 02:29:26 INFO - mozleak == 0.1 02:29:26 INFO - mozlog == 3.5 02:29:26 INFO - moznetwork == 0.27 02:29:26 INFO - mozprocess == 0.25 02:29:26 INFO - mozprofile == 0.28 02:29:26 INFO - mozrunner == 6.13 02:29:26 INFO - mozscreenshot == 0.1 02:29:26 INFO - mozsystemmonitor == 0.3 02:29:26 INFO - moztest == 0.8 02:29:26 INFO - mozversion == 1.4 02:29:26 INFO - psutil == 3.1.1 02:29:26 INFO - requests == 1.2.3 02:29:26 INFO - wsgiref == 0.1.2 02:29:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:26 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 0x21e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, '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': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:29:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:26 INFO - Using env: {'DISPLAY': ':0', 02:29:26 INFO - 'HOME': '/home/cltbld', 02:29:26 INFO - 'LANG': 'en_US.UTF-8', 02:29:26 INFO - 'LANGUAGE': 'en_US:en', 02:29:26 INFO - 'LOGNAME': 'cltbld', 02:29:26 INFO - 'MAIL': '/var/mail/cltbld', 02:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:26 INFO - 'MOZ_NO_REMOTE': '1', 02:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:26 INFO - 'NO_EM_RESTART': '1', 02:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:26 INFO - 'PWD': '/builds/slave/test', 02:29:26 INFO - 'SHELL': '/bin/bash', 02:29:26 INFO - 'SHLVL': '1', 02:29:26 INFO - 'TERM': 'linux', 02:29:26 INFO - 'TMOUT': '86400', 02:29:26 INFO - 'USER': 'cltbld', 02:29:26 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:26 INFO - '_': '/tools/buildbot/bin/python'} 02:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:29:26 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:29:27 INFO - Installing collected packages: simplejson 02:29:27 INFO - Running setup.py install for simplejson 02:29:27 INFO - building 'simplejson._speedups' extension 02:29:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 02:29:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 02:29:28 INFO - Successfully installed simplejson 02:29:28 INFO - Cleaning up... 02:29:28 INFO - Return code: 0 02:29:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, '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': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:29:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:29:28 INFO - Using env: {'DISPLAY': ':0', 02:29:28 INFO - 'HOME': '/home/cltbld', 02:29:28 INFO - 'LANG': 'en_US.UTF-8', 02:29:28 INFO - 'LANGUAGE': 'en_US:en', 02:29:28 INFO - 'LOGNAME': 'cltbld', 02:29:28 INFO - 'MAIL': '/var/mail/cltbld', 02:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:28 INFO - 'MOZ_NO_REMOTE': '1', 02:29:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:28 INFO - 'NO_EM_RESTART': '1', 02:29:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:28 INFO - 'PWD': '/builds/slave/test', 02:29:28 INFO - 'SHELL': '/bin/bash', 02:29:28 INFO - 'SHLVL': '1', 02:29:28 INFO - 'TERM': 'linux', 02:29:28 INFO - 'TMOUT': '86400', 02:29:28 INFO - 'USER': 'cltbld', 02:29:28 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:28 INFO - '_': '/tools/buildbot/bin/python'} 02:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:29:28 INFO - Cleaning up... 02:29:28 INFO - Return code: 0 02:29:28 INFO - Running post-action listener: _resource_record_post_action 02:29:28 INFO - Running post-action listener: _start_resource_monitoring 02:29:28 INFO - Starting resource monitoring. 02:29:28 INFO - [mozharness: 2017-09-01 09:29:28.973072Z] Finished create-virtualenv step (success) 02:29:28 INFO - [mozharness: 2017-09-01 09:29:28.973591Z] Running install step. 02:29:28 INFO - Running pre-action listener: _resource_record_pre_action 02:29:28 INFO - Running main action method: install 02:29:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:29 INFO - Reading from file tmpfile_stdout 02:29:29 INFO - Detecting whether we're running mozinstall >=1.0... 02:29:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:29 INFO - Reading from file tmpfile_stdout 02:29:29 INFO - Output received: 02:29:29 INFO - Usage: mozinstall [options] installer 02:29:29 INFO - Options: 02:29:29 INFO - -h, --help show this help message and exit 02:29:29 INFO - -d DEST, --destination=DEST 02:29:29 INFO - Directory to install application into. [default: 02:29:29 INFO - "/builds/slave/test"] 02:29:29 INFO - --app=APP Application being installed. [default: firefox] 02:29:29 INFO - mkdir: /builds/slave/test/build/application 02:29:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:29:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:29:45 INFO - Reading from file tmpfile_stdout 02:29:45 INFO - Output received: 02:29:45 INFO - /builds/slave/test/build/application/firefox/firefox 02:29:45 INFO - Running post-action listener: _resource_record_post_action 02:29:45 INFO - [mozharness: 2017-09-01 09:29:45.845318Z] Finished install step (success) 02:29:45 INFO - [mozharness: 2017-09-01 09:29:45.845568Z] Running setup-mitmproxy step. 02:29:45 INFO - Running pre-action listener: _resource_record_pre_action 02:29:45 INFO - Running main action method: setup_mitmproxy 02:29:45 INFO - Skipping: mitmproxy is not required 02:29:45 INFO - Running post-action listener: _resource_record_post_action 02:29:45 INFO - [mozharness: 2017-09-01 09:29:45.846680Z] Finished setup-mitmproxy step (success) 02:29:45 INFO - [mozharness: 2017-09-01 09:29:45.846898Z] Running run-tests step. 02:29:45 INFO - Running pre-action listener: _resource_record_pre_action 02:29:45 INFO - Running pre-action listener: _set_gcov_prefix 02:29:45 INFO - Running main action method: run_tests 02:29:45 WARNING - Try message not found. 02:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:45 INFO - Python 2.7.3 02:29:45 INFO - Return code: 0 02:29:45 INFO - grabbing minidump binary from tooltool 02:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:45 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 0x2558090>, '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 0x2558d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:29:45 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 02:29:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:29:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:45 INFO - Return code: 0 02:29:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:29:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:45 INFO - ENV: RUST_BACKTRACE is now full 02:29:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:29:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:29:45 INFO - Using env: {'DISPLAY': ':0', 02:29:45 INFO - 'HOME': '/home/cltbld', 02:29:45 INFO - 'LANG': 'en_US.UTF-8', 02:29:45 INFO - 'LANGUAGE': 'en_US:en', 02:29:45 INFO - 'LOGNAME': 'cltbld', 02:29:45 INFO - 'MAIL': '/var/mail/cltbld', 02:29:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:45 INFO - 'MOZ_NO_REMOTE': '1', 02:29:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:45 INFO - 'NO_EM_RESTART': '1', 02:29:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:45 INFO - 'PWD': '/builds/slave/test', 02:29:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:45 INFO - 'RUST_BACKTRACE': 'full', 02:29:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:29:45 INFO - 'SHELL': '/bin/bash', 02:29:45 INFO - 'SHLVL': '1', 02:29:45 INFO - 'STYLO_FORCE_ENABLED': '1', 02:29:45 INFO - 'TERM': 'linux', 02:29:45 INFO - 'TMOUT': '86400', 02:29:45 INFO - 'USER': 'cltbld', 02:29:45 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:45 INFO - '_': '/tools/buildbot/bin/python'} 02:29:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:29:46 INFO - mozversion application_buildid: 20170901083953 02:29:46 INFO - mozversion application_changeset: a3585c77e2b1bc5f5fea907e97762f7b47a12033 02:29:46 INFO - mozversion application_display_name: Nightly 02:29:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:46 INFO - mozversion application_name: Firefox 02:29:46 INFO - mozversion application_remotingname: firefox 02:29:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:29:46 INFO - mozversion application_vendor: Mozilla 02:29:46 INFO - mozversion application_version: 57.0a1 02:29:46 INFO - mozversion platform_buildid: 20170901083953 02:29:46 INFO - mozversion platform_changeset: a3585c77e2b1bc5f5fea907e97762f7b47a12033 02:29:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:29:46 INFO - mozversion platform_version: 57.0a1 02:29:46 INFO - using testdate: 1504258186 02:29:46 INFO - actual date: 1504258186 02:29:46 INFO - starting webserver on 'localhost:46167' 02:29:46 INFO - SUITE-START | Running 2 tests 02:29:46 INFO - TEST-START | dromaeo_css 02:29:46 INFO - Initialising browser for dromaeo_css test... 02:29:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46167/getInfo.html -profile /tmp/tmpFM0W72/profile 02:29:46 INFO - TEST-INFO | started process 15692 (/builds/slave/test/build/application/firefox/firefox http://localhost:46167/getInfo.html) 02:29:52 INFO - TEST-INFO | 15692: exit 0 02:29:52 INFO - Browser initialized. 02:29:52 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:52 INFO - Using env: {'DISPLAY': ':0', 02:29:52 INFO - 'HOME': '/home/cltbld', 02:29:52 INFO - 'JSGC_DISABLE_POISONING': '1', 02:29:52 INFO - 'LANG': 'en_US.UTF-8', 02:29:52 INFO - 'LANGUAGE': 'en_US:en', 02:29:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:29:52 INFO - 'LOGNAME': 'cltbld', 02:29:52 INFO - 'MAIL': '/var/mail/cltbld', 02:29:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:29:52 INFO - 'MOZ_CRASHREPORTER': '1', 02:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:29:52 INFO - 'MOZ_NO_REMOTE': '1', 02:29:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:52 INFO - 'NO_EM_RESTART': '1', 02:29:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:52 INFO - 'PWD': '/builds/slave/test', 02:29:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:52 INFO - 'RUST_BACKTRACE': 'full', 02:29:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:29:52 INFO - 'SHELL': '/bin/bash', 02:29:52 INFO - 'SHLVL': '1', 02:29:52 INFO - 'STYLO_FORCE_ENABLED': '1', 02:29:52 INFO - 'TERM': 'linux', 02:29:52 INFO - 'TMOUT': '86400', 02:29:52 INFO - 'USER': 'cltbld', 02:29:52 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:52 INFO - '_': '/tools/buildbot/bin/python'} 02:29:52 INFO - TEST-INFO | started process 15889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFM0W72/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:53 INFO - PID 15889 | 02:29:53 INFO - PID 15889 | (/builds/slave/test/build/application/firefox/firefox:15940): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:29:53 INFO - PID 15889 | 02:30:03 INFO - PID 15889 | RSS: Main: 167219200 02:30:03 INFO - PID 15889 | 02:33:01 INFO - PID 15889 | Cycle 1(1): loaded http://localhost:46167/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46167/tests/dromaeo/cssquery-ext.html) 02:33:03 INFO - PID 15889 | RSS: Main: 173277184 02:33:03 INFO - PID 15889 | 02:36:05 INFO - PID 15889 | Cycle 1(1): loaded http://localhost:46167/tests/dromaeo/cssquery-ext.html (next: http://localhost:46167/tests/dromaeo/cssquery-jquery.html) 02:36:06 INFO - PID 15889 | RSS: Main: 169746432 02:36:06 INFO - PID 15889 | 02:39:08 INFO - PID 15889 | Cycle 1(1): loaded http://localhost:46167/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46167/tests/dromaeo/cssquery-mootools.html) 02:39:08 INFO - PID 15889 | RSS: Main: 171474944 02:39:08 INFO - PID 15889 | 02:42:10 INFO - PID 15889 | Cycle 1(1): loaded http://localhost:46167/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46167/tests/dromaeo/cssquery-prototype.html) 02:42:11 INFO - PID 15889 | RSS: Main: 170270720 02:42:11 INFO - PID 15889 | 02:45:14 INFO - PID 15889 | Cycle 1(1): loaded http://localhost:46167/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46167/tests/dromaeo/cssquery-yui.html) 02:45:15 INFO - PID 15889 | RSS: Main: 171671552 02:45:15 INFO - PID 15889 | 02:48:17 INFO - PID 15889 | Cycle 1(1): loaded http://localhost:46167/tests/dromaeo/cssquery-yui.html (next: http://localhost:46167/tests/dromaeo/cssquery-dojo.html) 02:48:17 INFO - PID 15889 | RSS: Main: 174030848 02:48:17 INFO - PID 15889 | 02:48:17 INFO - PID 15889 | __start_tp_report 02:48:17 INFO - PID 15889 | _x_x_mozilla_page_load 02:48:17 INFO - PID 15889 | _x_x_mozilla_page_load_details 02:48:17 INFO - PID 15889 | |i|pagename|runs| 02:48:17 INFO - PID 15889 | |0;dojo.html;3330.8334583270844;3344.1305260632234;3345.1135449105986;3367;3368.461046232603;6420.06908998195;6473.093766872642;6476.8380790480205;6477.902831457532;6483.092367068614;6353.142325786018;6415.0377443383395;6423.614583125016;6432.163818703562;6450.645214513212;5899.941000589982;5904.6161999470005;5926.318473375574;5942.286925568917;5946.316173640022;5962.0162673158875;5969.611975221596;6004.789832355868;6031.909521357161;6050.576459647827;5909.084091965752;5984.072468767348;5985.970070149665;6000.249968753906;6038.63768173911;5704.80033198836;5927.140564618149;5939.109133629969;6014.398560143973;6042.063480160538;5400.270963419927;5800.7679692812235;5809.564282678773;5922.7038648067855;5970.910436343458;6366.968165159191;6409.423151916303;6456.774012909573;6466.6766661666725;6507.479401647857;6886.655667216619;6894.620795856226;6943.201531823804;6966.12923384577;6976.302369763034;6992.405645520086;7000.124984376953;7023.227444981048;7033.8944915826305;7042.2957704229675;6450.903236451457;6517.608943463408;6519.837004074889;6530.151080359523;6555.704993275314;7117.857642847218;7121.786346409616;7146.106736657918;7217.458690598226;7234.927650723425;6730.697118629673;6787.1176747023555;6849.04113424121;6850.280720524373;6958.686859091353;6880.965595172092;6883.174019117738;6885.552439091443;6889.586624802528;6895.069165662671;6853.862922741518;6854.383105520527;6855.862882742317;6868.862622747517;6869.381755642016;6889;6896;6901.275366086589;6904.689288982008;7009.1589009319205;5448.564114870801;5483.232347471358;5485.588580856452;5504.669719816744;5504.834854954278;4758.476567577563;4771.594414474808;4776.808927642855;4826.396700412449;4863.416390033219;5983.790567330123;6027.939720602825;6057.878842423127;6064.151018857364;6091.725872335755;6961.860762784716;6972.26791186918;6974.232834388214;7020.05229294049;7042.119735033121;5840.1531777892815;5892.499137573269;5918.7928422505;5983.551233657493;6047.0929360596265;5917.615355001911;5989.520838332923;6032.065029920282;6045.33501314855;6054.788082417094;6795.694193761293;6816.897746533806;6822.079019332326;6828.385445309847;6847.965760171167;6964.26875178099;6973.267806880204;7056.964715176391;7068.68190931399;7075.257098004841;6606.207255129415;6722;6736.797896063126;6838.042674025541;6842.5552339099795;6700.095487109373;6737.191537015589;6743.932560674527;6744.81965656018;6746.359095885883;6930.029795828487;6956.756513522002;6957.79126626202;6985.126859142607;7065.116860392451;6760.628165450948;6782.355676210752;6806.081179040964;6816.556923799937;6825.385715285648;6708.463322934056;6714.798556043326;6786.796396108125;6802.319768023158;6827.863442731217;6519.511036672174;6597.934020659732;6641.833954151185;6664.5334826561675;6758.391744742997;6742.359475849787;6771.322867713189;6835.863282734417;6843.794686159423;6844.555103918229;6524.3149469305035;6533.771318003847;6550.639714815731;6620.702068406837;6660.167479065117 02:48:17 INFO - PID 15889 | |1;ext.html;763.5533213070354;858.5406807358063;882.7042940614893;1143.1083754671354;1150.7295785490414;31038.534421983688;41551.376729349045;42227.788861055655;42322;42389;41872.58127418725;42075.57924420755;42123.57876421235;42171.78914105425;42189.57810421895;7596.810079748009;7619.5809230492305;7658.61706914652;7676.884846727304;7710.537367757925;7558.508696934709;7694.846103077935;7705.653245603933;7758.883616745753;7766.766996990099;7180.281971802804;7729.922700772977;7766.689332426696;7795.5712435816;7813.843723125534;7665.310122089011;7696.384289256846;7736.226377362247;7738.264864837832;7738.380929525681;7524.247575242487;7569;7621.695132194761;7702.383809295243;7709.036370453693;36095.458568121496;36148.45777313342;36376.090597735005;36509.45235821465;36558.63441365579;3440.896773096811;3460.0311912664242;3461.4115600348;3461.8442170102153;3472.0451875734125;31589.526157107663;31682.366352672816;31708.682913171033;31712.524312135338;31740.04779856585;5172.870678233036;5213.2701421800975;5226.033183860998;5231.947680523184;5238.345206849144;186813;188515;188610;188631.99999999863;188802;48652.27021594679;48904.7554762231;48942.755286224055;48975.265371019465;49122.75438622855;50748.492515075115;51128.48871511312;51168.74415627898;51272.74363628158;51301.48698513042;14038.227897465533;14250.85749142516;14283.214423206824;14291.071080379743;14293.78559321611;11218.887811121947;11219.775604488028;11220.943895280634;11294.040006599367;11307.151963602599;12549.623511294676;14158.929205353907;14276.785848212285;14403.351849166813;14429.134251944917;4937;5467.261919640877;5556.083246264402;5556.138798486241;5610.354809196913;1014.4521958142668;1017.2929813779352;1020.1277907389224;1022.4683164754287;1039.158281791736;23566.646500302508;23623.763762362156;23817.404564886016;23917.880410598184;24073;1097.160672085855;1113.716002419378;1116.8380584815156;1125.5216532973454;1126.7633796902578;12357.629271121881;12426.068044896669;12431.93784031074;15903.920480397524;16258.837411625733;229307;231922;232042;232056;232447;56534;56585.71707141438;56717.14924276139;56965.14552281719;56988.14517782236;1427.7786943023848;1436.8563143685758;1445.5229774174497;1449.681070164562;1463.604826696807;27510.449791004467;27571.724282756917;27801.721982779913;27920.16239512818;28201;49702.751486242334;50272.74863625658;50279.245811312125;50349.74825125851;50366.7481662604;208407;210678;210689;210805;211035;34744;34770;34803;34991.30017399587;35155.29689406147;42523.574764251964;42898.57101428946;42943.7852810734;43046.139077218904;43088.78455607702;30974.690253097182;31393.529097063107;31737;31846.68153318437;31976.200594985312;31067;31153.221169470944;31328.686713132578;31419.685803141678;31461.213469663442;33716.49425258672;34660.65339346675;34923.65076349204;35281.64718352886;35385;27685.72314276912;27919.162425127277;28213.294667632654;28312;28417.43165136727;22937.50312234654;23317.3004809856;23346.75789379151;23830.404239894142;23976.520469590858 02:48:17 INFO - PID 15889 | |2;jquery.html;556.8774869528705;561.2787567975153;563.1468325486887;566.8129517259305;571.0777094991588;21390.144394224233;21513.677294840552;22119.557608847812;22173.002214900327;22312.21907233247;24530;25037.49925001499;25114.748852511468;25141;25142.371440713945;6713.395794378517;6875.621840798754;6877.174739031323;6878.140232470942;6879.587224766505;6420.133282006939;6674.265830758637;7061.082059332305;7087;7099.361057504823;7077.787666369992;7078.787636370917;7080.327368899946;7083.822904427379;7085.256048114951;7061.399781018619;7068.929310706879;7078.787636370891;7086.85826283474;7087.220405755363;6913.343232392915;6926.238113807477;6928.515003949726;6935.375816176568;6951.965240173818;30422.239444013856;30748.8462557688;31847;32038.359232815445;32149.51775723366;2083.4166433398673;2114.6299397605358;2117.819985301251;2118.8728676279475;2119.501917049492;20491.38525844227;21110.26114086015;21170.682439763415;21187.5762484751;21207.363779086652;2305.7233132024267;2425.7089149302196;2478.4671295671337;2534.556452620784;2544.109561653425;137168;140031;140175;140482.29758851242;140959;20034.89982550078;20341.288054918008;21299;21771;21998.560028799333;42289.36565951513;42524.36213456801;42537.574624253364;42716.864156792035;42939;10995.835062474069;11056.064652561861;11116.11071114325;11134.610288639858;11203.383813890152;9265.351425400291;9311.580978855833;9332.860007099898;9366.90633093674;9410.95294523523;9366.718998430058;10878.945605271923;10927.945360273307;11240.269382490113;11692.298462092302;3504.7021003214504;3506.701930335899;3584.4623306503713;4024.6579040781276;4051.817668204879;910.5310764956052;911.1389736698889;929.3587424677087;939.9342046056711;946.9242460603135;19650.526210534266;19836;19879.801201987793;20155.798442015686;20407.183712651626;990.4008075114596;1046.931949423285;1051.8106740786732;1058.8358804385332;1068.56188962525;10519.433853033352;10879.673609791718;10941.945290273497;11136.220464567403;11199.944000279946;497929;504932;511390;515192;516233;121510;121764.39117804354;124537.13194302093;124547;125203;1212.987155724962;1214.8906598406186;1238.8389509363726;1240.7208378114851;1245.3897590180927;17287;17511.562210944827;17895.37366192177;17967.550811229823;18058.81941180624;52863;53251.733741331045;54485;58037;58796.4120358791;66851.66574167098;67285.32714672791;67372.32627673661;68121.31878681348;68273.65863170652;11972.281663100242;14443.205623690797;14805;14894.404223830925;15112.395504179709;22883.199088031837;22951.540969180856;23357.883210583837;23930.521389571764;24148.75851241465;17836.643267134845;18530.44408667742;18702.53243668919;18713.064346782878;18898.14958326851;17105.059221742922;17107.828921711123;17269.39557115545;17299.221535031207;17685;18907.338243161423;19382.12780424856;19453.221871125563;19529;19547.413577592695;14603.342849571582;14760.409583616534;14815.481458149345;14841.109533428029;14849.851501484847;18413.63172736511;19346.8065319345;19384.12771425261;19388.70916936275;19394.903025484782 02:48:17 INFO - PID 15889 | |3;mootools.html;1356.2066191278104;1370.7669696151654;1399.5311570623844;1403.214200047974;1407.106487380513;14734.852651473482;15094.698106037873;15284.312205950731;15457.845421545795;15471.690566188683;15358.308876100575;15593.298301576428;15593.688126237497;15606.921965390156;15609.141497217648;2611.8955241790354;2662.9733702663016;2664.8800803963773;2666.77332426743;2670.532656785065;2684.5973104034356;2739.315171207198;2745.3685652299982;2747.738964798341;2781.276868014321;2648.562987107125;2676.7992400569938;2681.9061332853353;2682.7719643830296;2684.7315268473194;2638.432736961553;2662.4275780707144;2689.825161364525;2693.313205132679;2695.3665888516184;2660.3615132368286;2681.839089654627;2686.1807148819603;2688.677358716947;2690.1929421173763;14525.418983240657;14544;14653;14718.264086795618;14741.778873316909;7842.098158711707;8084.1107478177355;8115.918840811575;8160.6327715252955;8164.877526837102;5341.866453338659;5445.264889239941;5533.418991005927;5613.214150019001;5656.547476201894;1930.710393440981;1949.3372253433758;1964.5579744557504;2023.3525271912995;2027.2803154879953;26295.21114366572;26369.604455933175;26542.20373388782;26564.33589160267;26567;6787.355201255873;6866.176058872968;6937.479689023344;7058.153021637437;7083.362497375261;17075.658486830194;17087.401940932257;17111;17120.14399280031;17152.05663688484;7914.802129946682;8022.1175670676175;8023.037235531657;8025.558594277254;8049.074356448966;7321.926780732231;7405.407567394595;7442.367398771165;7458.552486850698;7506.24937506245;5267.1309233976735;5406.91861627676;5418.485243901823;5419.918701219485;5443.918341224885;1526.63360793411;1533.463287849244;1538.7845701601784;1545.3233241932628;1546.536039188239;891.6477991193572;920.5489310238069;921.4839689773748;922.4280945813639;936.4849332866896;7427;7486.401087912954;7543.547387156788;7547.73582924595;7594.734184303523;706.6643344411466;716.9211386747454;717.989230161548;721.4949535325293;722.0901663903519;4359.433273674402;4367.27939889921;4383.254846676073;4414.227510185705;4452.732836029849;20504.282350117974;21664;21687.240946566795;21893.67159492609;21929;21213.257535986166;21409.250676226256;21413.67879481809;21794.78205217959;21895;9177.357584969122;9422.775039244854;10281.99999999985;11278.943605281922;11705.712371639112;8664.956675216583;8778.604962776819;9099.545022748704;9212.032736562564;9335.06649335061;18659.626807463505;18715.90642046781;18812.435626931216;19022.7146592804;19536;19164.904175479034;19222.32721854784;19247;19635.901820491377;19703.901480492506;13312.267825269315;13761.931190343985;13802.792958105436;13823.308834558433;13855.722885542435;13959.860401395856;13975.371108299947;14091.295435227992;14111.929440352733;14128.576142715736;11351.14866385008;11420.885791141982;11454.255473394203;11488.770224595628;11618.88381116178;11243.100551955988;11416.257943233662;11563.537458501565;11705.356205408785;11773.705657358634;9050.275977921881;9116.954415228147;9157.679481218125;9617.855732164164;9650.758731031781;6867.622280774605;6872.518923675295;6921.377076063179;6938.375546200866;7019.368256856703;6973.721051157897;7076.752313668997;7097.467689923174;7128.215896251512;7133.607651579213 02:48:17 INFO - PID 15889 | |4;prototype.html;493.04103518656217;493.2502596053997;504.4400715206118;529.6239669834417;534.041395694728;6296.716647750856;6335.334789847069;6380.5852619579755;6427;6445.581037232577;6254.499640028798;6295.401936815995;6298.496120310376;6308;6394.328595497475;5223.373195216579;5245.947540524594;5259.158534634459;5267.604929630274;5272.260154149121;5077.949220507803;5106.591472682195;5193.195054766498;5225.425203227642;5257.7108259045735;5213.452587478317;5237.502437268454;5238.4499627539135;5258.395284542269;5321.654092483996;5107.616928730323;5237.764300606444;5244.239585260135;5249.4488078751565;5253.238280449333;4726.220173671374;4849.636277279183;4921.655484116113;4939.55544001038;4949.7772600233075;6149.200603921507;6249.937500624981;6319.905201421982;6323.588966717195;6350.936490635081;6056.454919057306;6104.816855494342;6124.846878828065;6151.200343955302;6197.504199664016;6763.864722705518;6767.661616919184;6781.762638307685;6795.116634837489;6865.485088618324;5357.223202635596;5378.515933565959;5379.139337705988;5405.675659460445;5408.891822163574;6672.666366681695;6821.693023813841;6989.860202795967;7027.227005029443;7076.646167691646;5474.562035037267;5492.567145534042;5515.4760297771645;5537.280153580089;5567.220589117527;5561.555075593943;5584.46947539983;5603.3836278009385;5640.830775076754;5669.291338582677;6004.7598096076545;6020.277566691937;6056.878862422727;6066.906917113589;6093.847653808691;6629.7679581214425;6789.592624442549;6796.592204467748;6808.787144178063;6832.555883867532;1932.5072106612724;1961.8136277053657;1966.1545535419907;1973.0430741090847;1989.303743689697;1389.4858902206047;1448.507507447473;1450;1453.273363318341;1462.8098347214793;5832.270966129234;5866;5921.63134746105;5945.2865656120675;6012.488938440194;6741.157355330583;6856.868616678292;6880.793576192722;6888.793336199922;6900.3444672756;5911.704414779244;5915.822525324161;6020.879582408327;6035.849103772441;6043.365446628129;3635.364360838371;3659.323025240286;3660.5790334111384;3694.464302676097;3701.3892707703467;6655.301193374626;6669.733210671616;6735.865282694319;6749.831254218586;6759.256481787;7102.183248926336;7105.360517553445;7111.217766045731;7134.892976605355;7146.785596432115;6668.933310666831;6687.699053542505;6717.899231511531;6718.32816718334;6741.191057073084;6869.519133660596;6900.2409734930125;6935.133108361455;6952.826179345355;6975.337342952412;6706.524564595781;6727.327267273234;6732.831679208059;6808.557443766139;6839.418649414656;6477.838054048498;6652.368025037614;6657.234418041987;6692.962590798434;6717.899231511434;5359.410464848941;5492.258545096521;5524.259858144672;5541.224228608079;5579.581531385081;6379.266384365857;6600.669966501751;6754.966225168842;6803.523753337417;6913.688884000333;6602.240742314503;6616.735330586723;6657.900131497934;6696.895012322912;6708.127943367331;6522.445592124723;6546;6606.744718503469;6670.199576050905;6686.732530698912;6505.51208659343;6542.934570654423;6612.867742645024;6627.635480048643;6642.4021838034805;6560.507961902781;6676.198856137295;6723.697433615597;6740.66296685154;6782.321767823179;6512.5766825156215;6558.508111891531;6558.770443034471;6570.5400621956005;6577.704003319766 02:48:17 INFO - PID 15889 | |5;yui.html;913.342393476697;923.492079356354;936.7845395559021;937.6062053937345;947.7630592351915;15828.76256856148;16566.420175293868;16743.832561674393;21142.15431382743;21784.564308713816;19353.806461935375;19870;19998.400047998548;20049.398518044483;20098.799012009877;922.8062106957531;943.2783920300941;946.2146418472652;947.2327414794005;948.2508818033745;944.7354740672588;944.8204841080208;952.4618590496382;987.9308448408615;998.6354980432126;978.3982850546898;978.4178413843787;978.4814048554242;979.9951000244989;1033.7777377863758;973.8344481438172;974.1768205866032;974.878140232471;975.5805003848342;1032.9225308101923;714.4463041143104;715.4669771020571;716.1191734166952;722.2236096196609;761.5925479868243;16871;17492;17548.210330535156;17584.032878191694;18911.716324255147;21378.358649240392;21397.144114235412;21608.135674573154;21872.23447179341;22024.339269821772;16275.267612957326;16350.264238109314;16367.59081022972;16479.752803707954;16524.917375413046;3740.0649837540614;3798.924021519582;3803.866864659751;3804.8668296609762;3806.276807406584;114884.99999999917;120238;121330;125349;126454;16363.836361636231;16661.50015499537;17269.309227631005;17394.304227831;17413.651726965643;24359;26326;29628;30274.394512109633;30305.545416818764;8424.74725758216;8638.78403039929;8648.913510864937;8676.262517686067;8705.47767133974;4052.412400202013;4072.490938632671;4132;4160.271952408377;4161.750294982311;4996.175631020841;5114.8977020460125;5116.386033675908;5117.232415137759;5131.589472842164;1066.6160182334354;1107.1032463704425;1112.22700223344;1112.365951407706;1112.4604566785104;660.7357057177071;703.6516924122592;706.7137809187185;722.0793488302352;726.8800647893144;11466.369349685681;11484.770304593862;11975.46110425033;12531.624051278477;13129.67175820593;715.2739968931538;716.5521549031855;723.6237156678498;727.6980053277937;728.613834667627;5207.7916883325;5341.278927344836;5403.972980135074;5546.69493177871;5581.553475722014;110733;110970;111700;112961;113331.43334283275;97422;99167.50416248017;99228;99879;101016;89962.55018724996;91213;91949.54025229964;91994.54002729943;93063;12394.574623918365;12503.062270329943;12511.186772859735;12525.434320709912;12688.365581721062;40355;40583.18833623252;41980.58019419889;42009;43106.56893431026;70601;71086.64456677891;72327.27672723205;73876.26123738693;75792.62103689446;17889.463316100537;18528.0735963204;18716.15777289998;18735.34426295073;18794.71807922855;19745.802541974972;20394.69407958852;20530.28144015012;20583.58832823365;20631.381058568266;15757.684846303238;15977.760333595237;16284.674306513567;16298.348066077699;16324.428644997368;16293;16373.67252654964;16378.5086447402;16387.918060409622;16438.917805411376;18259;19153.904230479315;19166.520836979187;19261.940593267504;19295.51761205981;12403.317817520123;12586.118971671895;12851;13040;13095.934520327337;12616.74766504683;12991.935040324737;13275.535356262484;13537.187768733907;13916.373763180884 02:48:17 INFO - PID 15889 | __end_tp_report 02:48:17 INFO - PID 15889 | __start_cc_report 02:48:17 INFO - PID 15889 | _x_x_mozilla_cycle_collect,351 02:48:17 INFO - PID 15889 | __end_cc_report 02:48:17 INFO - PID 15889 | __startTimestamp1504259297346__endTimestamp 02:48:17 INFO - PID 15889 | 02:48:17 INFO - PID 15889 | ------- Summary: start ------- 02:48:17 INFO - PID 15889 | Number of tests: 6 02:48:17 INFO - PID 15889 | 02:48:17 INFO - PID 15889 | [#0] dojo.html Cycles:165 Average:6381.73 Median:6641.83 stddev:754.73 (11.4%) stddev-sans-first:718.10 02:48:17 INFO - PID 15889 | Values: 3330.8 3344.1 3345.1 3367.0 3368.5 6420.1 6473.1 6476.8 6477.9 6483.1 6353.1 6415.0 6423.6 6432.2 6450.6 5899.9 5904.6 5926.3 5942.3 5946.3 5962.0 5969.6 6004.8 6031.9 6050.6 5909.1 5984.1 5986.0 6000.2 6038.6 5704.8 5927.1 5939.1 6014.4 6042.1 5400.3 5800.8 5809.6 5922.7 5970.9 6367.0 6409.4 6456.8 6466.7 6507.5 6886.7 6894.6 6943.2 6966.1 6976.3 6992.4 7000.1 7023.2 7033.9 7042.3 6450.9 6517.6 6519.8 6530.2 6555.7 7117.9 7121.8 7146.1 7217.5 7234.9 6730.7 6787.1 6849.0 6850.3 6958.7 6881.0 6883.2 6885.6 6889.6 6895.1 6853.9 6854.4 6855.9 6868.9 6869.4 6889.0 6896.0 6901.3 6904.7 7009.2 5448.6 5483.2 5485.6 5504.7 5504.8 4758.5 4771.6 4776.8 4826.4 4863.4 5983.8 6027.9 6057.9 6064.2 6091.7 6961.9 6972.3 6974.2 7020.1 7042.1 5840.2 5892.5 5918.8 5983.6 6047.1 5917.6 5989.5 6032.1 6045.3 6054.8 6795.7 6816.9 6822.1 6828.4 6848.0 6964.3 6973.3 7057.0 7068.7 7075.3 6606.2 6722.0 6736.8 6838.0 6842.6 6700.1 6737.2 6743.9 6744.8 6746.4 6930.0 6956.8 6957.8 6985.1 7065.1 6760.6 6782.4 6806.1 6816.6 6825.4 6708.5 6714.8 6786.8 6802.3 6827.9 6519.5 6597.9 6641.8 6664.5 6758.4 6742.4 6771.3 6835.9 6843.8 6844.6 6524.3 6533.8 6550.6 6620.7 6660.2 02:48:17 INFO - PID 15889 | 02:48:17 INFO - PID 15889 | [#1] ext.html Cycles:180 Average:38225.79 Median:3460.72 stddev:54713.82 (1581.0%) stddev-sans-first:54795.00 02:48:17 INFO - PID 15889 | Values: 763.6 858.5 882.7 1143.1 1150.7 31038.5 41551.4 42227.8 42322.0 42389.0 41872.6 42075.6 42123.6 42171.8 42189.6 7596.8 7619.6 7658.6 7676.9 7710.5 7558.5 7694.8 7705.7 7758.9 7766.8 7180.3 7729.9 7766.7 7795.6 7813.8 7665.3 7696.4 7736.2 7738.3 7738.4 7524.2 7569.0 7621.7 7702.4 7709.0 36095.5 36148.5 36376.1 36509.5 36558.6 3440.9 3460.0 3461.4 3461.8 3472.0 31589.5 31682.4 31708.7 31712.5 31740.0 5172.9 5213.3 5226.0 5231.9 5238.3 186813.0 188515.0 188610.0 188632.0 188802.0 48652.3 48904.8 48942.8 48975.3 49122.8 50748.5 51128.5 51168.7 51272.7 51301.5 14038.2 14250.9 14283.2 14291.1 14293.8 11218.9 11219.8 11220.9 11294.0 11307.2 12549.6 14158.9 14276.8 14403.4 14429.1 4937.0 5467.3 5556.1 5556.1 5610.4 1014.5 1017.3 1020.1 1022.5 1039.2 23566.6 23623.8 23817.4 23917.9 24073.0 1097.2 1113.7 1116.8 1125.5 1126.8 12357.6 12426.1 12431.9 15903.9 16258.8 229307.0 231922.0 232042.0 232056.0 232447.0 56534.0 56585.7 56717.1 56965.1 56988.1 1427.8 1436.9 1445.5 1449.7 1463.6 27510.4 27571.7 27801.7 27920.2 28201.0 49702.8 50272.7 50279.2 50349.7 50366.7 208407.0 210678.0 210689.0 210805.0 211035.0 34744.0 34770.0 34803.0 34991.3 35155.3 42523.6 42898.6 42943.8 43046.1 43088.8 30974.7 31393.5 31737.0 31846.7 31976.2 31067.0 31153.2 31328.7 31419.7 31461.2 33716.5 34660.7 34923.7 35281.6 35385.0 27685.7 27919.2 28213.3 28312.0 28417.4 22937.5 23317.3 23346.8 23830.4 23976.5 02:48:17 INFO - PID 15889 | 02:48:17 INFO - PID 15889 | [#2] jquery.html Cycles:180 Average:36449.62 Median:22146.28 stddev:85624.68 (386.6%) stddev-sans-first:85822.47 02:48:17 INFO - PID 15889 | Values: 556.9 561.3 563.1 566.8 571.1 21390.1 21513.7 22119.6 22173.0 22312.2 24530.0 25037.5 25114.7 25141.0 25142.4 6713.4 6875.6 6877.2 6878.1 6879.6 6420.1 6674.3 7061.1 7087.0 7099.4 7077.8 7078.8 7080.3 7083.8 7085.3 7061.4 7068.9 7078.8 7086.9 7087.2 6913.3 6926.2 6928.5 6935.4 6952.0 30422.2 30748.8 31847.0 32038.4 32149.5 2083.4 2114.6 2117.8 2118.9 2119.5 20491.4 21110.3 21170.7 21187.6 21207.4 2305.7 2425.7 2478.5 2534.6 2544.1 137168.0 140031.0 140175.0 140482.3 140959.0 20034.9 20341.3 21299.0 21771.0 21998.6 42289.4 42524.4 42537.6 42716.9 42939.0 10995.8 11056.1 11116.1 11134.6 11203.4 9265.4 9311.6 9332.9 9366.9 9411.0 9366.7 10878.9 10927.9 11240.3 11692.3 3504.7 3506.7 3584.5 4024.7 4051.8 910.5 911.1 929.4 939.9 946.9 19650.5 19836.0 19879.8 20155.8 20407.2 990.4 1046.9 1051.8 1058.8 1068.6 10519.4 10879.7 10941.9 11136.2 11199.9 497929.0 504932.0 511390.0 515192.0 516233.0 121510.0 121764.4 124537.1 124547.0 125203.0 1213.0 1214.9 1238.8 1240.7 1245.4 17287.0 17511.6 17895.4 17967.6 18058.8 52863.0 53251.7 54485.0 58037.0 58796.4 66851.7 67285.3 67372.3 68121.3 68273.7 11972.3 14443.2 14805.0 14894.4 15112.4 22883.2 22951.5 23357.9 23930.5 24148.8 17836.6 18530.4 18702.5 18713.1 18898.1 17105.1 17107.8 17269.4 17299.2 17685.0 18907.3 19382.1 19453.2 19529.0 19547.4 14603.3 14760.4 14815.5 14841.1 14849.9 18413.6 19346.8 19384.1 19388.7 19394.9 02:48:17 INFO - PID 15889 | 02:48:17 INFO - PID 15889 | [#3] mootools.html Cycles:180 Average:9364.80 Median:2668.65 stddev:6740.03 (252.6%) stddev-sans-first:6732.07 02:48:17 INFO - PID 15889 | Values: 1356.2 1370.8 1399.5 1403.2 1407.1 14734.9 15094.7 15284.3 15457.8 15471.7 15358.3 15593.3 15593.7 15606.9 15609.1 2611.9 2663.0 2664.9 2666.8 2670.5 2684.6 2739.3 2745.4 2747.7 2781.3 2648.6 2676.8 2681.9 2682.8 2684.7 2638.4 2662.4 2689.8 2693.3 2695.4 2660.4 2681.8 2686.2 2688.7 2690.2 14525.4 14544.0 14653.0 14718.3 14741.8 7842.1 8084.1 8115.9 8160.6 8164.9 5341.9 5445.3 5533.4 5613.2 5656.5 1930.7 1949.3 1964.6 2023.4 2027.3 26295.2 26369.6 26542.2 26564.3 26567.0 6787.4 6866.2 6937.5 7058.2 7083.4 17075.7 17087.4 17111.0 17120.1 17152.1 7914.8 8022.1 8023.0 8025.6 8049.1 7321.9 7405.4 7442.4 7458.6 7506.2 5267.1 5406.9 5418.5 5419.9 5443.9 1526.6 1533.5 1538.8 1545.3 1546.5 891.6 920.5 921.5 922.4 936.5 7427.0 7486.4 7543.5 7547.7 7594.7 706.7 716.9 718.0 721.5 722.1 4359.4 4367.3 4383.3 4414.2 4452.7 20504.3 21664.0 21687.2 21893.7 21929.0 21213.3 21409.3 21413.7 21794.8 21895.0 9177.4 9422.8 10282.0 11278.9 11705.7 8665.0 8778.6 9099.5 9212.0 9335.1 18659.6 18715.9 18812.4 19022.7 19536.0 19164.9 19222.3 19247.0 19635.9 19703.9 13312.3 13761.9 13802.8 13823.3 13855.7 13959.9 13975.4 14091.3 14111.9 14128.6 11351.1 11420.9 11454.3 11488.8 11618.9 11243.1 11416.3 11563.5 11705.4 11773.7 9050.3 9117.0 9157.7 9617.9 9650.8 6867.6 6872.5 6921.4 6938.4 7019.4 6973.7 7076.8 7097.5 7128.2 7133.6 02:48:17 INFO - PID 15889 | 02:48:17 INFO - PID 15889 | [#4] prototype.html Cycles:180 Average:5733.84 Median:6303.25 stddev:1536.98 (24.4%) stddev-sans-first:1490.10 02:48:17 INFO - PID 15889 | Values: 493.0 493.3 504.4 529.6 534.0 6296.7 6335.3 6380.6 6427.0 6445.6 6254.5 6295.4 6298.5 6308.0 6394.3 5223.4 5245.9 5259.2 5267.6 5272.3 5077.9 5106.6 5193.2 5225.4 5257.7 5213.5 5237.5 5238.4 5258.4 5321.7 5107.6 5237.8 5244.2 5249.4 5253.2 4726.2 4849.6 4921.7 4939.6 4949.8 6149.2 6249.9 6319.9 6323.6 6350.9 6056.5 6104.8 6124.8 6151.2 6197.5 6763.9 6767.7 6781.8 6795.1 6865.5 5357.2 5378.5 5379.1 5405.7 5408.9 6672.7 6821.7 6989.9 7027.2 7076.6 5474.6 5492.6 5515.5 5537.3 5567.2 5561.6 5584.5 5603.4 5640.8 5669.3 6004.8 6020.3 6056.9 6066.9 6093.8 6629.8 6789.6 6796.6 6808.8 6832.6 1932.5 1961.8 1966.2 1973.0 1989.3 1389.5 1448.5 1450.0 1453.3 1462.8 5832.3 5866.0 5921.6 5945.3 6012.5 6741.2 6856.9 6880.8 6888.8 6900.3 5911.7 5915.8 6020.9 6035.8 6043.4 3635.4 3659.3 3660.6 3694.5 3701.4 6655.3 6669.7 6735.9 6749.8 6759.3 7102.2 7105.4 7111.2 7134.9 7146.8 6668.9 6687.7 6717.9 6718.3 6741.2 6869.5 6900.2 6935.1 6952.8 6975.3 6706.5 6727.3 6732.8 6808.6 6839.4 6477.8 6652.4 6657.2 6693.0 6717.9 5359.4 5492.3 5524.3 5541.2 5579.6 6379.3 6600.7 6755.0 6803.5 6913.7 6602.2 6616.7 6657.9 6696.9 6708.1 6522.4 6546.0 6606.7 6670.2 6686.7 6505.5 6542.9 6612.9 6627.6 6642.4 6560.5 6676.2 6723.7 6740.7 6782.3 6512.6 6558.5 6558.8 6570.5 6577.7 02:48:17 INFO - PID 15889 | 02:48:17 INFO - PID 15889 | [#5] yui.html Cycles:180 Average:23733.00 Median:21828.40 stddev:32454.66 (148.7%) stddev-sans-first:32500.47 02:48:17 INFO - PID 15889 | Values: 913.3 923.5 936.8 937.6 947.8 15828.8 16566.4 16743.8 21142.2 21784.6 19353.8 19870.0 19998.4 20049.4 20098.8 922.8 943.3 946.2 947.2 948.3 944.7 944.8 952.5 987.9 998.6 978.4 978.4 978.5 980.0 1033.8 973.8 974.2 974.9 975.6 1032.9 714.4 715.5 716.1 722.2 761.6 16871.0 17492.0 17548.2 17584.0 18911.7 21378.4 21397.1 21608.1 21872.2 22024.3 16275.3 16350.3 16367.6 16479.8 16524.9 3740.1 3798.9 3803.9 3804.9 3806.3 114885.0 120238.0 121330.0 125349.0 126454.0 16363.8 16661.5 17269.3 17394.3 17413.7 24359.0 26326.0 29628.0 30274.4 30305.5 8424.7 8638.8 8648.9 8676.3 8705.5 4052.4 4072.5 4132.0 4160.3 4161.8 4996.2 5114.9 5116.4 5117.2 5131.6 1066.6 1107.1 1112.2 1112.4 1112.5 660.7 703.7 706.7 722.1 726.9 11466.4 11484.8 11975.5 12531.6 13129.7 715.3 716.6 723.6 727.7 728.6 5207.8 5341.3 5404.0 5546.7 5581.6 110733.0 110970.0 111700.0 112961.0 113331.4 97422.0 99167.5 99228.0 99879.0 101016.0 89962.6 91213.0 91949.5 91994.5 93063.0 12394.6 12503.1 12511.2 12525.4 12688.4 40355.0 40583.2 41980.6 42009.0 43106.6 70601.0 71086.6 72327.3 73876.3 75792.6 17889.5 18528.1 18716.2 18735.3 18794.7 19745.8 20394.7 20530.3 20583.6 20631.4 15757.7 15977.8 16284.7 16298.3 16324.4 16293.0 16373.7 16378.5 16387.9 16438.9 18259.0 19153.9 19166.5 19261.9 19295.5 12403.3 12586.1 12851.0 13040.0 13095.9 12616.7 12991.9 13275.5 13537.2 13916.4 02:48:17 INFO - PID 15889 | -------- Summary: end -------- 02:48:17 INFO - PID 15889 | 02:48:18 INFO - TEST-INFO | 15889: exit 0 02:48:18 INFO - TEST-OK | dromaeo_css | took 1111872ms 02:48:18 INFO - TEST-START | kraken 02:48:18 INFO - Initialising browser for kraken test... 02:48:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46167/getInfo.html -profile /tmp/tmppsi9Xm/profile 02:48:18 INFO - TEST-INFO | started process 16268 (/builds/slave/test/build/application/firefox/firefox http://localhost:46167/getInfo.html) 02:48:23 INFO - TEST-INFO | 16268: exit 0 02:48:24 INFO - Browser initialized. 02:48:24 INFO - Running cycle 1/1 for kraken test... 02:48:24 INFO - Using env: {'DISPLAY': ':0', 02:48:24 INFO - 'HOME': '/home/cltbld', 02:48:24 INFO - 'JSGC_DISABLE_POISONING': '1', 02:48:24 INFO - 'LANG': 'en_US.UTF-8', 02:48:24 INFO - 'LANGUAGE': 'en_US:en', 02:48:24 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:48:24 INFO - 'LOGNAME': 'cltbld', 02:48:24 INFO - 'MAIL': '/var/mail/cltbld', 02:48:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:48:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:48:24 INFO - 'MOZ_CRASHREPORTER': '1', 02:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:24 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:48:24 INFO - 'MOZ_NO_REMOTE': '1', 02:48:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:48:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:48:24 INFO - 'NO_EM_RESTART': '1', 02:48:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:24 INFO - 'PWD': '/builds/slave/test', 02:48:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:48:24 INFO - 'RUST_BACKTRACE': 'full', 02:48:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:48:24 INFO - 'SHELL': '/bin/bash', 02:48:24 INFO - 'SHLVL': '1', 02:48:24 INFO - 'STYLO_FORCE_ENABLED': '1', 02:48:24 INFO - 'TERM': 'linux', 02:48:24 INFO - 'TMOUT': '86400', 02:48:24 INFO - 'USER': 'cltbld', 02:48:24 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081', 02:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:24 INFO - '_': '/tools/buildbot/bin/python'} 02:48:24 INFO - TEST-INFO | started process 16468 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppsi9Xm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:25 INFO - PID 16468 | 02:48:25 INFO - PID 16468 | (/builds/slave/test/build/application/firefox/firefox:16521): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:48:25 INFO - PID 16468 | 02:48:35 INFO - PID 16468 | RSS: Main: 167550976 02:48:35 INFO - PID 16468 | 02:48:37 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46167/tests/kraken/driver.html?testName=audio-beat-detection) 02:48:38 INFO - PID 16468 | RSS: Main: 172748800 02:48:38 INFO - PID 16468 | 02:48:41 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46167/tests/kraken/driver.html?testName=audio-dft) 02:48:42 INFO - PID 16468 | RSS: Main: 176619520 02:48:42 INFO - PID 16468 | 02:48:45 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46167/tests/kraken/driver.html?testName=audio-fft) 02:48:45 INFO - PID 16468 | RSS: Main: 169504768 02:48:45 INFO - PID 16468 | 02:48:47 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46167/tests/kraken/driver.html?testName=audio-oscillator) 02:48:48 INFO - PID 16468 | RSS: Main: 170463232 02:48:48 INFO - PID 16468 | 02:48:50 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46167/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:51 INFO - PID 16468 | RSS: Main: 171212800 02:48:51 INFO - PID 16468 | 02:48:54 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46167/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:55 INFO - PID 16468 | RSS: Main: 172040192 02:48:55 INFO - PID 16468 | 02:48:58 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46167/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:59 INFO - PID 16468 | RSS: Main: 171806720 02:48:59 INFO - PID 16468 | 02:49:02 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46167/tests/kraken/driver.html?testName=json-parse-financial) 02:49:02 INFO - PID 16468 | RSS: Main: 172179456 02:49:02 INFO - PID 16468 | 02:49:04 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46167/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:05 INFO - PID 16468 | RSS: Main: 171806720 02:49:05 INFO - PID 16468 | 02:49:06 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46167/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:07 INFO - PID 16468 | RSS: Main: 171704320 02:49:07 INFO - PID 16468 | 02:49:09 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46167/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:49:10 INFO - PID 16468 | RSS: Main: 171737088 02:49:10 INFO - PID 16468 | 02:49:12 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46167/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:49:13 INFO - PID 16468 | RSS: Main: 172339200 02:49:13 INFO - PID 16468 | 02:49:16 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46167/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:17 INFO - PID 16468 | RSS: Main: 172584960 02:49:17 INFO - PID 16468 | 02:49:18 INFO - PID 16468 | Cycle 1(1): loaded http://localhost:46167/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46167/tests/kraken/driver.html?testName=ai-astar) 02:49:19 INFO - PID 16468 | RSS: Main: 173875200 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | __start_tp_report 02:49:19 INFO - PID 16468 | _x_x_mozilla_page_load 02:49:19 INFO - PID 16468 | _x_x_mozilla_page_load_details 02:49:19 INFO - PID 16468 | |i|pagename|runs| 02:49:19 INFO - PID 16468 | |0;ai-astar;99;108;100;107;109;113;108;97;110;107 02:49:19 INFO - PID 16468 | |1;audio-beat-detection;147;115;142;166;115;145;146;116;165;117 02:49:19 INFO - PID 16468 | |2;audio-dft;162;162;162;164;162;163;164;164;168;163 02:49:19 INFO - PID 16468 | |3;audio-fft;73;82;74;166;74;73;73;85;94;74 02:49:19 INFO - PID 16468 | |4;audio-oscillator;79;80;131;80;79;78;131;80;79;127 02:49:19 INFO - PID 16468 | |5;imaging-gaussian-blur;114;115;115;115;113;116;113;114;118;113 02:49:19 INFO - PID 16468 | |6;imaging-darkroom;148;148;148;150;149;148;148;150;148;147 02:49:19 INFO - PID 16468 | |7;imaging-desaturate;94;93;101;93;94;94;95;93;95;94 02:49:19 INFO - PID 16468 | |8;json-parse-financial;55;55;55;56;56;56;55;54;57;55 02:49:19 INFO - PID 16468 | |9;json-stringify-tinderbox;38;39;39;38;38;38;38;38;39;39 02:49:19 INFO - PID 16468 | |10;stanford-crypto-aes;76;77;78;78;73;78;79;75;75;74 02:49:19 INFO - PID 16468 | |11;stanford-crypto-ccm;127;123;129;123;121;118;121;130;128;164 02:49:19 INFO - PID 16468 | |12;stanford-crypto-pbkdf2;174;174;168;174;177;183;181;174;172;175 02:49:19 INFO - PID 16468 | |13;stanford-crypto-sha256-iterative;63;67;67;65;66;64;65;65;69;64 02:49:19 INFO - PID 16468 | __end_tp_report 02:49:19 INFO - PID 16468 | __start_cc_report 02:49:19 INFO - PID 16468 | _x_x_mozilla_cycle_collect,951 02:49:19 INFO - PID 16468 | __end_cc_report 02:49:19 INFO - PID 16468 | __startTimestamp1504259359163__endTimestamp 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | ------- Summary: start ------- 02:49:19 INFO - PID 16468 | Number of tests: 14 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#0] ai-astar Cycles:10 Average:105.80 Median:109.50 stddev:5.27 (4.8%) stddev-sans-first:4.98 02:49:19 INFO - PID 16468 | Values: 99.0 108.0 100.0 107.0 109.0 113.0 108.0 97.0 110.0 107.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:145.50 stddev:20.28 (13.9%) stddev-sans-first:21.21 02:49:19 INFO - PID 16468 | Values: 147.0 115.0 142.0 166.0 115.0 145.0 146.0 116.0 165.0 117.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#2] audio-dft Cycles:10 Average:163.40 Median:163.50 stddev:1.84 (1.1%) stddev-sans-first:1.88 02:49:19 INFO - PID 16468 | Values: 162.0 162.0 162.0 164.0 162.0 163.0 164.0 164.0 168.0 163.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#3] audio-fft Cycles:10 Average:86.80 Median:74.00 stddev:28.70 (38.8%) stddev-sans-first:30.00 02:49:19 INFO - PID 16468 | Values: 73.0 82.0 74.0 166.0 74.0 73.0 73.0 85.0 94.0 74.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:79.00 stddev:24.37 (30.8%) stddev-sans-first:25.20 02:49:19 INFO - PID 16468 | Values: 79.0 80.0 131.0 80.0 79.0 78.0 131.0 80.0 79.0 127.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:115.00 stddev:1.58 (1.4%) stddev-sans-first:1.66 02:49:19 INFO - PID 16468 | Values: 114.0 115.0 115.0 115.0 113.0 116.0 113.0 114.0 118.0 113.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.00 stddev:0.97 (0.7%) stddev-sans-first:1.01 02:49:19 INFO - PID 16468 | Values: 148.0 148.0 148.0 150.0 149.0 148.0 148.0 150.0 148.0 147.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:94.00 stddev:2.37 (2.5%) stddev-sans-first:2.50 02:49:19 INFO - PID 16468 | Values: 94.0 93.0 101.0 93.0 94.0 94.0 95.0 93.0 95.0 94.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.50 stddev:0.84 (1.5%) stddev-sans-first:0.88 02:49:19 INFO - PID 16468 | Values: 55.0 55.0 55.0 56.0 56.0 56.0 55.0 54.0 57.0 55.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.50 stddev:0.52 (1.3%) stddev-sans-first:0.53 02:49:19 INFO - PID 16468 | Values: 38.0 39.0 39.0 38.0 38.0 38.0 38.0 38.0 39.0 39.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:77.50 stddev:2.00 (2.6%) stddev-sans-first:2.12 02:49:19 INFO - PID 16468 | Values: 76.0 77.0 78.0 78.0 73.0 78.0 79.0 75.0 75.0 74.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#11] stanford-crypto-ccm Cycles:10 Average:128.40 Median:127.50 stddev:13.12 (10.3%) stddev-sans-first:13.90 02:49:19 INFO - PID 16468 | Values: 127.0 123.0 129.0 123.0 121.0 118.0 121.0 130.0 128.0 164.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:174.50 stddev:4.29 (2.5%) stddev-sans-first:4.53 02:49:19 INFO - PID 16468 | Values: 174.0 174.0 168.0 174.0 177.0 183.0 181.0 174.0 172.0 175.0 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.50 stddev:1.78 (2.7%) stddev-sans-first:1.64 02:49:19 INFO - PID 16468 | Values: 63.0 67.0 67.0 65.0 66.0 64.0 65.0 65.0 69.0 64.0 02:49:19 INFO - PID 16468 | -------- Summary: end -------- 02:49:19 INFO - PID 16468 | 02:49:19 INFO - PID 16468 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 02:49:19 INFO - TEST-INFO | 16468: exit 0 02:49:19 INFO - TEST-OK | kraken | took 61828ms 02:49:19 INFO - SUITE-END | took 1173s 02:49:20 INFO - Completed test suite (00:19:34) 02:49:20 INFO - javascript benchmark 02:49:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8722.852719276214, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.8334583270844, 3344.1305260632234, 3345.1135449105986, 3367.0, 3368.461046232603, 6420.06908998195, 6473.093766872642, 6476.8380790480205, 6477.902831457532, 6483.092367068614, 6353.142325786018, 6415.0377443383395, 6423.614583125016, 6432.163818703562, 6450.645214513212, 5899.941000589982, 5904.6161999470005, 5926.318473375574, 5942.286925568917, 5946.316173640022, 5962.0162673158875, 5969.611975221596, 6004.789832355868, 6031.909521357161, 6050.576459647827, 5909.084091965752, 5984.072468767348, 5985.970070149665, 6000.249968753906, 6038.63768173911, 5704.80033198836, 5927.140564618149, 5939.109133629969, 6014.398560143973, 6042.063480160538, 5400.270963419927, 5800.7679692812235, 5809.564282678773, 5922.7038648067855, 5970.910436343458, 6366.968165159191, 6409.423151916303, 6456.774012909573, 6466.6766661666725, 6507.479401647857, 6886.655667216619, 6894.620795856226, 6943.201531823804, 6966.12923384577, 6976.302369763034, 6992.405645520086, 7000.124984376953, 7023.227444981048, 7033.8944915826305, 7042.2957704229675, 6450.903236451457, 6517.608943463408, 6519.837004074889, 6530.151080359523, 6555.704993275314, 7117.857642847218, 7121.786346409616, 7146.106736657918, 7217.458690598226, 7234.927650723425, 6730.697118629673, 6787.1176747023555, 6849.04113424121, 6850.280720524373, 6958.686859091353, 6880.965595172092, 6883.174019117738, 6885.552439091443, 6889.586624802528, 6895.069165662671, 6853.862922741518, 6854.383105520527, 6855.862882742317, 6868.862622747517, 6869.381755642016, 6889.0, 6896.0, 6901.275366086589, 6904.689288982008, 7009.1589009319205, 5448.564114870801, 5483.232347471358, 5485.588580856452, 5504.669719816744, 5504.834854954278, 4758.476567577563, 4771.594414474808, 4776.808927642855, 4826.396700412449, 4863.416390033219, 5983.790567330123, 6027.939720602825, 6057.878842423127, 6064.151018857364, 6091.725872335755, 6961.860762784716, 6972.26791186918, 6974.232834388214, 7020.05229294049, 7042.119735033121, 5840.1531777892815, 5892.499137573269, 5918.7928422505, 5983.551233657493, 6047.0929360596265, 5917.615355001911, 5989.520838332923, 6032.065029920282, 6045.33501314855, 6054.788082417094, 6795.694193761293, 6816.897746533806, 6822.079019332326, 6828.385445309847, 6847.965760171167, 6964.26875178099, 6973.267806880204, 7056.964715176391, 7068.68190931399, 7075.257098004841, 6606.207255129415, 6722.0, 6736.797896063126, 6838.042674025541, 6842.5552339099795, 6700.095487109373, 6737.191537015589, 6743.932560674527, 6744.81965656018, 6746.359095885883, 6930.029795828487, 6956.756513522002, 6957.79126626202, 6985.126859142607, 7065.116860392451, 6760.628165450948, 6782.355676210752, 6806.081179040964, 6816.556923799937, 6825.385715285648, 6708.463322934056, 6714.798556043326, 6786.796396108125, 6802.319768023158, 6827.863442731217, 6519.511036672174, 6597.934020659732, 6641.833954151185, 6664.5334826561675, 6758.391744742997, 6742.359475849787, 6771.322867713189, 6835.863282734417, 6843.794686159423, 6844.555103918229, 6524.3149469305035, 6533.771318003847, 6550.639714815731, 6620.702068406837, 6660.167479065117], "value": 6325.957432216194, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.5533213070354, 858.5406807358063, 882.7042940614893, 1143.1083754671354, 1150.7295785490414, 31038.534421983688, 41551.376729349045, 42227.788861055655, 42322.0, 42389.0, 41872.58127418725, 42075.57924420755, 42123.57876421235, 42171.78914105425, 42189.57810421895, 7596.810079748009, 7619.5809230492305, 7658.61706914652, 7676.884846727304, 7710.537367757925, 7558.508696934709, 7694.846103077935, 7705.653245603933, 7758.883616745753, 7766.766996990099, 7180.281971802804, 7729.922700772977, 7766.689332426696, 7795.5712435816, 7813.843723125534, 7665.310122089011, 7696.384289256846, 7736.226377362247, 7738.264864837832, 7738.380929525681, 7524.247575242487, 7569.0, 7621.695132194761, 7702.383809295243, 7709.036370453693, 36095.458568121496, 36148.45777313342, 36376.090597735005, 36509.45235821465, 36558.63441365579, 3440.896773096811, 3460.0311912664242, 3461.4115600348, 3461.8442170102153, 3472.0451875734125, 31589.526157107663, 31682.366352672816, 31708.682913171033, 31712.524312135338, 31740.04779856585, 5172.870678233036, 5213.2701421800975, 5226.033183860998, 5231.947680523184, 5238.345206849144, 186813.0, 188515.0, 188610.0, 188631.99999999863, 188802.0, 48652.27021594679, 48904.7554762231, 48942.755286224055, 48975.265371019465, 49122.75438622855, 50748.492515075115, 51128.48871511312, 51168.74415627898, 51272.74363628158, 51301.48698513042, 14038.227897465533, 14250.85749142516, 14283.214423206824, 14291.071080379743, 14293.78559321611, 11218.887811121947, 11219.775604488028, 11220.943895280634, 11294.040006599367, 11307.151963602599, 12549.623511294676, 14158.929205353907, 14276.785848212285, 14403.351849166813, 14429.134251944917, 4937.0, 5467.261919640877, 5556.083246264402, 5556.138798486241, 5610.354809196913, 1014.4521958142668, 1017.2929813779352, 1020.1277907389224, 1022.4683164754287, 1039.158281791736, 23566.646500302508, 23623.763762362156, 23817.404564886016, 23917.880410598184, 24073.0, 1097.160672085855, 1113.716002419378, 1116.8380584815156, 1125.5216532973454, 1126.7633796902578, 12357.629271121881, 12426.068044896669, 12431.93784031074, 15903.920480397524, 16258.837411625733, 229307.0, 231922.0, 232042.0, 232056.0, 232447.0, 56534.0, 56585.71707141438, 56717.14924276139, 56965.14552281719, 56988.14517782236, 1427.7786943023848, 1436.8563143685758, 1445.5229774174497, 1449.681070164562, 1463.604826696807, 27510.449791004467, 27571.724282756917, 27801.721982779913, 27920.16239512818, 28201.0, 49702.751486242334, 50272.74863625658, 50279.245811312125, 50349.74825125851, 50366.7481662604, 208407.0, 210678.0, 210689.0, 210805.0, 211035.0, 34744.0, 34770.0, 34803.0, 34991.30017399587, 35155.29689406147, 42523.574764251964, 42898.57101428946, 42943.7852810734, 43046.139077218904, 43088.78455607702, 30974.690253097182, 31393.529097063107, 31737.0, 31846.68153318437, 31976.200594985312, 31067.0, 31153.221169470944, 31328.686713132578, 31419.685803141678, 31461.213469663442, 33716.49425258672, 34660.65339346675, 34923.65076349204, 35281.64718352886, 35385.0, 27685.72314276912, 27919.162425127277, 28213.294667632654, 28312.0, 28417.43165136727, 22937.50312234654, 23317.3004809856, 23346.75789379151, 23830.404239894142, 23976.520469590858], "value": 17289.867586422526, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.8774869528705, 561.2787567975153, 563.1468325486887, 566.8129517259305, 571.0777094991588, 21390.144394224233, 21513.677294840552, 22119.557608847812, 22173.002214900327, 22312.21907233247, 24530.0, 25037.49925001499, 25114.748852511468, 25141.0, 25142.371440713945, 6713.395794378517, 6875.621840798754, 6877.174739031323, 6878.140232470942, 6879.587224766505, 6420.133282006939, 6674.265830758637, 7061.082059332305, 7087.0, 7099.361057504823, 7077.787666369992, 7078.787636370917, 7080.327368899946, 7083.822904427379, 7085.256048114951, 7061.399781018619, 7068.929310706879, 7078.787636370891, 7086.85826283474, 7087.220405755363, 6913.343232392915, 6926.238113807477, 6928.515003949726, 6935.375816176568, 6951.965240173818, 30422.239444013856, 30748.8462557688, 31847.0, 32038.359232815445, 32149.51775723366, 2083.4166433398673, 2114.6299397605358, 2117.819985301251, 2118.8728676279475, 2119.501917049492, 20491.38525844227, 21110.26114086015, 21170.682439763415, 21187.5762484751, 21207.363779086652, 2305.7233132024267, 2425.7089149302196, 2478.4671295671337, 2534.556452620784, 2544.109561653425, 137168.0, 140031.0, 140175.0, 140482.29758851242, 140959.0, 20034.89982550078, 20341.288054918008, 21299.0, 21771.0, 21998.560028799333, 42289.36565951513, 42524.36213456801, 42537.574624253364, 42716.864156792035, 42939.0, 10995.835062474069, 11056.064652561861, 11116.11071114325, 11134.610288639858, 11203.383813890152, 9265.351425400291, 9311.580978855833, 9332.860007099898, 9366.90633093674, 9410.95294523523, 9366.718998430058, 10878.945605271923, 10927.945360273307, 11240.269382490113, 11692.298462092302, 3504.7021003214504, 3506.701930335899, 3584.4623306503713, 4024.6579040781276, 4051.817668204879, 910.5310764956052, 911.1389736698889, 929.3587424677087, 939.9342046056711, 946.9242460603135, 19650.526210534266, 19836.0, 19879.801201987793, 20155.798442015686, 20407.183712651626, 990.4008075114596, 1046.931949423285, 1051.8106740786732, 1058.8358804385332, 1068.56188962525, 10519.433853033352, 10879.673609791718, 10941.945290273497, 11136.220464567403, 11199.944000279946, 497929.0, 504932.0, 511390.0, 515192.0, 516233.0, 121510.0, 121764.39117804354, 124537.13194302093, 124547.0, 125203.0, 1212.987155724962, 1214.8906598406186, 1238.8389509363726, 1240.7208378114851, 1245.3897590180927, 17287.0, 17511.562210944827, 17895.37366192177, 17967.550811229823, 18058.81941180624, 52863.0, 53251.733741331045, 54485.0, 58037.0, 58796.4120358791, 66851.66574167098, 67285.32714672791, 67372.32627673661, 68121.31878681348, 68273.65863170652, 11972.281663100242, 14443.205623690797, 14805.0, 14894.404223830925, 15112.395504179709, 22883.199088031837, 22951.540969180856, 23357.883210583837, 23930.521389571764, 24148.75851241465, 17836.643267134845, 18530.44408667742, 18702.53243668919, 18713.064346782878, 18898.14958326851, 17105.059221742922, 17107.828921711123, 17269.39557115545, 17299.221535031207, 17685.0, 18907.338243161423, 19382.12780424856, 19453.221871125563, 19529.0, 19547.413577592695, 14603.342849571582, 14760.409583616534, 14815.481458149345, 14841.109533428029, 14849.851501484847, 18413.63172736511, 19346.8065319345, 19384.12771425261, 19388.70916936275, 19394.903025484782], "value": 12724.588783663066, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.2066191278104, 1370.7669696151654, 1399.5311570623844, 1403.214200047974, 1407.106487380513, 14734.852651473482, 15094.698106037873, 15284.312205950731, 15457.845421545795, 15471.690566188683, 15358.308876100575, 15593.298301576428, 15593.688126237497, 15606.921965390156, 15609.141497217648, 2611.8955241790354, 2662.9733702663016, 2664.8800803963773, 2666.77332426743, 2670.532656785065, 2684.5973104034356, 2739.315171207198, 2745.3685652299982, 2747.738964798341, 2781.276868014321, 2648.562987107125, 2676.7992400569938, 2681.9061332853353, 2682.7719643830296, 2684.7315268473194, 2638.432736961553, 2662.4275780707144, 2689.825161364525, 2693.313205132679, 2695.3665888516184, 2660.3615132368286, 2681.839089654627, 2686.1807148819603, 2688.677358716947, 2690.1929421173763, 14525.418983240657, 14544.0, 14653.0, 14718.264086795618, 14741.778873316909, 7842.098158711707, 8084.1107478177355, 8115.918840811575, 8160.6327715252955, 8164.877526837102, 5341.866453338659, 5445.264889239941, 5533.418991005927, 5613.214150019001, 5656.547476201894, 1930.710393440981, 1949.3372253433758, 1964.5579744557504, 2023.3525271912995, 2027.2803154879953, 26295.21114366572, 26369.604455933175, 26542.20373388782, 26564.33589160267, 26567.0, 6787.355201255873, 6866.176058872968, 6937.479689023344, 7058.153021637437, 7083.362497375261, 17075.658486830194, 17087.401940932257, 17111.0, 17120.14399280031, 17152.05663688484, 7914.802129946682, 8022.1175670676175, 8023.037235531657, 8025.558594277254, 8049.074356448966, 7321.926780732231, 7405.407567394595, 7442.367398771165, 7458.552486850698, 7506.24937506245, 5267.1309233976735, 5406.91861627676, 5418.485243901823, 5419.918701219485, 5443.918341224885, 1526.63360793411, 1533.463287849244, 1538.7845701601784, 1545.3233241932628, 1546.536039188239, 891.6477991193572, 920.5489310238069, 921.4839689773748, 922.4280945813639, 936.4849332866896, 7427.0, 7486.401087912954, 7543.547387156788, 7547.73582924595, 7594.734184303523, 706.6643344411466, 716.9211386747454, 717.989230161548, 721.4949535325293, 722.0901663903519, 4359.433273674402, 4367.27939889921, 4383.254846676073, 4414.227510185705, 4452.732836029849, 20504.282350117974, 21664.0, 21687.240946566795, 21893.67159492609, 21929.0, 21213.257535986166, 21409.250676226256, 21413.67879481809, 21794.78205217959, 21895.0, 9177.357584969122, 9422.775039244854, 10281.99999999985, 11278.943605281922, 11705.712371639112, 8664.956675216583, 8778.604962776819, 9099.545022748704, 9212.032736562564, 9335.06649335061, 18659.626807463505, 18715.90642046781, 18812.435626931216, 19022.7146592804, 19536.0, 19164.904175479034, 19222.32721854784, 19247.0, 19635.901820491377, 19703.901480492506, 13312.267825269315, 13761.931190343985, 13802.792958105436, 13823.308834558433, 13855.722885542435, 13959.860401395856, 13975.371108299947, 14091.295435227992, 14111.929440352733, 14128.576142715736, 11351.14866385008, 11420.885791141982, 11454.255473394203, 11488.770224595628, 11618.88381116178, 11243.100551955988, 11416.257943233662, 11563.537458501565, 11705.356205408785, 11773.705657358634, 9050.275977921881, 9116.954415228147, 9157.679481218125, 9617.855732164164, 9650.758731031781, 6867.622280774605, 6872.518923675295, 6921.377076063179, 6938.375546200866, 7019.368256856703, 6973.721051157897, 7076.752313668997, 7097.467689923174, 7128.215896251512, 7133.607651579213], "value": 6628.5233211594095, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.04103518656217, 493.2502596053997, 504.4400715206118, 529.6239669834417, 534.041395694728, 6296.716647750856, 6335.334789847069, 6380.5852619579755, 6427.0, 6445.581037232577, 6254.499640028798, 6295.401936815995, 6298.496120310376, 6308.0, 6394.328595497475, 5223.373195216579, 5245.947540524594, 5259.158534634459, 5267.604929630274, 5272.260154149121, 5077.949220507803, 5106.591472682195, 5193.195054766498, 5225.425203227642, 5257.7108259045735, 5213.452587478317, 5237.502437268454, 5238.4499627539135, 5258.395284542269, 5321.654092483996, 5107.616928730323, 5237.764300606444, 5244.239585260135, 5249.4488078751565, 5253.238280449333, 4726.220173671374, 4849.636277279183, 4921.655484116113, 4939.55544001038, 4949.7772600233075, 6149.200603921507, 6249.937500624981, 6319.905201421982, 6323.588966717195, 6350.936490635081, 6056.454919057306, 6104.816855494342, 6124.846878828065, 6151.200343955302, 6197.504199664016, 6763.864722705518, 6767.661616919184, 6781.762638307685, 6795.116634837489, 6865.485088618324, 5357.223202635596, 5378.515933565959, 5379.139337705988, 5405.675659460445, 5408.891822163574, 6672.666366681695, 6821.693023813841, 6989.860202795967, 7027.227005029443, 7076.646167691646, 5474.562035037267, 5492.567145534042, 5515.4760297771645, 5537.280153580089, 5567.220589117527, 5561.555075593943, 5584.46947539983, 5603.3836278009385, 5640.830775076754, 5669.291338582677, 6004.7598096076545, 6020.277566691937, 6056.878862422727, 6066.906917113589, 6093.847653808691, 6629.7679581214425, 6789.592624442549, 6796.592204467748, 6808.787144178063, 6832.555883867532, 1932.5072106612724, 1961.8136277053657, 1966.1545535419907, 1973.0430741090847, 1989.303743689697, 1389.4858902206047, 1448.507507447473, 1450.0, 1453.273363318341, 1462.8098347214793, 5832.270966129234, 5866.0, 5921.63134746105, 5945.2865656120675, 6012.488938440194, 6741.157355330583, 6856.868616678292, 6880.793576192722, 6888.793336199922, 6900.3444672756, 5911.704414779244, 5915.822525324161, 6020.879582408327, 6035.849103772441, 6043.365446628129, 3635.364360838371, 3659.323025240286, 3660.5790334111384, 3694.464302676097, 3701.3892707703467, 6655.301193374626, 6669.733210671616, 6735.865282694319, 6749.831254218586, 6759.256481787, 7102.183248926336, 7105.360517553445, 7111.217766045731, 7134.892976605355, 7146.785596432115, 6668.933310666831, 6687.699053542505, 6717.899231511531, 6718.32816718334, 6741.191057073084, 6869.519133660596, 6900.2409734930125, 6935.133108361455, 6952.826179345355, 6975.337342952412, 6706.524564595781, 6727.327267273234, 6732.831679208059, 6808.557443766139, 6839.418649414656, 6477.838054048498, 6652.368025037614, 6657.234418041987, 6692.962590798434, 6717.899231511434, 5359.410464848941, 5492.258545096521, 5524.259858144672, 5541.224228608079, 5579.581531385081, 6379.266384365857, 6600.669966501751, 6754.966225168842, 6803.523753337417, 6913.688884000333, 6602.240742314503, 6616.735330586723, 6657.900131497934, 6696.895012322912, 6708.127943367331, 6522.445592124723, 6546.0, 6606.744718503469, 6670.199576050905, 6686.732530698912, 6505.51208659343, 6542.934570654423, 6612.867742645024, 6627.635480048643, 6642.4021838034805, 6560.507961902781, 6676.198856137295, 6723.697433615597, 6740.66296685154, 6782.321767823179, 6512.5766825156215, 6558.508111891531, 6558.770443034471, 6570.5400621956005, 6577.704003319766], "value": 5287.241440290194, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [913.342393476697, 923.492079356354, 936.7845395559021, 937.6062053937345, 947.7630592351915, 15828.76256856148, 16566.420175293868, 16743.832561674393, 21142.15431382743, 21784.564308713816, 19353.806461935375, 19870.0, 19998.400047998548, 20049.398518044483, 20098.799012009877, 922.8062106957531, 943.2783920300941, 946.2146418472652, 947.2327414794005, 948.2508818033745, 944.7354740672588, 944.8204841080208, 952.4618590496382, 987.9308448408615, 998.6354980432126, 978.3982850546898, 978.4178413843787, 978.4814048554242, 979.9951000244989, 1033.7777377863758, 973.8344481438172, 974.1768205866032, 974.878140232471, 975.5805003848342, 1032.9225308101923, 714.4463041143104, 715.4669771020571, 716.1191734166952, 722.2236096196609, 761.5925479868243, 16871.0, 17492.0, 17548.210330535156, 17584.032878191694, 18911.716324255147, 21378.358649240392, 21397.144114235412, 21608.135674573154, 21872.23447179341, 22024.339269821772, 16275.267612957326, 16350.264238109314, 16367.59081022972, 16479.752803707954, 16524.917375413046, 3740.0649837540614, 3798.924021519582, 3803.866864659751, 3804.8668296609762, 3806.276807406584, 114884.99999999917, 120238.0, 121330.0, 125349.0, 126454.0, 16363.836361636231, 16661.50015499537, 17269.309227631005, 17394.304227831, 17413.651726965643, 24359.0, 26326.0, 29628.0, 30274.394512109633, 30305.545416818764, 8424.74725758216, 8638.78403039929, 8648.913510864937, 8676.262517686067, 8705.47767133974, 4052.412400202013, 4072.490938632671, 4132.0, 4160.271952408377, 4161.750294982311, 4996.175631020841, 5114.8977020460125, 5116.386033675908, 5117.232415137759, 5131.589472842164, 1066.6160182334354, 1107.1032463704425, 1112.22700223344, 1112.365951407706, 1112.4604566785104, 660.7357057177071, 703.6516924122592, 706.7137809187185, 722.0793488302352, 726.8800647893144, 11466.369349685681, 11484.770304593862, 11975.46110425033, 12531.624051278477, 13129.67175820593, 715.2739968931538, 716.5521549031855, 723.6237156678498, 727.6980053277937, 728.613834667627, 5207.7916883325, 5341.278927344836, 5403.972980135074, 5546.69493177871, 5581.553475722014, 110733.0, 110970.0, 111700.0, 112961.0, 113331.43334283275, 97422.0, 99167.50416248017, 99228.0, 99879.0, 101016.0, 89962.55018724996, 91213.0, 91949.54025229964, 91994.54002729943, 93063.0, 12394.574623918365, 12503.062270329943, 12511.186772859735, 12525.434320709912, 12688.365581721062, 40355.0, 40583.18833623252, 41980.58019419889, 42009.0, 43106.56893431026, 70601.0, 71086.64456677891, 72327.27672723205, 73876.26123738693, 75792.62103689446, 17889.463316100537, 18528.0735963204, 18716.15777289998, 18735.34426295073, 18794.71807922855, 19745.802541974972, 20394.69407958852, 20530.28144015012, 20583.58832823365, 20631.381058568266, 15757.684846303238, 15977.760333595237, 16284.674306513567, 16298.348066077699, 16324.428644997368, 16293.0, 16373.67252654964, 16378.5086447402, 16387.918060409622, 16438.917805411376, 18259.0, 19153.904230479315, 19166.520836979187, 19261.940593267504, 19295.51761205981, 12403.317817520123, 12586.118971671895, 12851.0, 13040.0, 13095.934520327337, 12616.74766504683, 12991.935040324737, 13275.535356262484, 13537.187768733907, 13916.373763180884], "value": 9030.643554734841, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 100.0, 107.0, 109.0, 113.0, 108.0, 97.0, 110.0, 107.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 142.0, 166.0, 115.0, 145.0, 146.0, 116.0, 165.0, 117.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 164.0, 162.0, 163.0, 164.0, 164.0, 168.0, 163.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 82.0, 74.0, 166.0, 74.0, 73.0, 73.0, 85.0, 94.0, 74.0], "value": 86.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 131.0, 80.0, 79.0, 78.0, 131.0, 80.0, 79.0, 127.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 115.0, 113.0, 116.0, 113.0, 114.0, 118.0, 113.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 150.0, 149.0, 148.0, 148.0, 150.0, 148.0, 147.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 101.0, 93.0, 94.0, 94.0, 95.0, 93.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 56.0, 56.0, 56.0, 55.0, 54.0, 57.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 39.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 78.0, 73.0, 78.0, 79.0, 75.0, 75.0, 74.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 123.0, 129.0, 123.0, 121.0, 118.0, 121.0, 130.0, 128.0, 164.0], "value": 128.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 168.0, 174.0, 177.0, 183.0, 181.0, 174.0, 172.0, 175.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 67.0, 65.0, 66.0, 64.0, 65.0, 65.0, 69.0, 64.0], "value": 65.5, "unit": "score"}]}]} 02:49:20 INFO - Return code: 0 02:49:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:49:20 INFO - # TBPL SUCCESS # 02:49:20 INFO - Running post-action listener: _package_coverage_data 02:49:20 INFO - Running post-action listener: _resource_record_post_action 02:49:20 INFO - [mozharness: 2017-09-01 09:49:20.426260Z] Finished run-tests step (success) 02:49:20 INFO - Running post-run listener: _resource_record_post_run 02:49:20 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 339968; Write bytes: 403599360; Read time: 92; Write time: 257704 02:49:20 INFO - TinderboxPrint: CPU usage
13.1% 02:49:20 INFO - TinderboxPrint: I/O read bytes / time
339,968 / 92 02:49:20 INFO - TinderboxPrint: I/O write bytes / time
403,599,360 / 257,704 02:49:20 INFO - TinderboxPrint: CPU idle
8,250.1 (86.9%) 02:49:20 INFO - TinderboxPrint: CPU user
1,195.7 (12.6%) 02:49:20 INFO - TinderboxPrint: Swap in / out
0 / 0 02:49:20 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109694976; Read time: 0; Write time: 83236 02:49:20 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 02:49:20 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 339968; Write bytes: 293474304; Read time: 92; Write time: 174436 02:49:21 INFO - Running post-run listener: _upload_blobber_files 02:49:21 INFO - Blob upload gear active. 02:49:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:49:21 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 02:49:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:49:21 INFO - (blobuploader) - INFO - Open directory for files ... 02:49:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:49:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:49:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:22 INFO - (blobuploader) - INFO - Done attempting. 02:49:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:49:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:49:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:26 INFO - (blobuploader) - INFO - Done attempting. 02:49:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:49:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:49:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:27 INFO - (blobuploader) - INFO - Done attempting. 02:49:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:49:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:49:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:28 INFO - (blobuploader) - INFO - Done attempting. 02:49:28 INFO - (blobuploader) - INFO - Iteration through files over. 02:49:28 INFO - Return code: 0 02:49:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:49:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:49:28 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2ac8583b9fea50d8edd37b9664040eeae92eb94d49e94f91ee337a0e1db710d415b5c000ebda58517c03f254001bd75d65b1880aa4aad838c83bd1c13c3394e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eda5d2a34985e5827d42edb673ac83e38c90f6c130710fd1794af85ea1cbbbe33c2b5cb3337f9333cdf64a72ca995fed03512da191d0e9626b2d9dd487d95c6c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4250ec25d2416add843324baf78a175796184f56466d980bf89ec08b9d96b29019fd94a56204ed70467402f9fd7329d8834d4a5a19f41eae7cb2abc332c0756", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af9c7754268abdcd3f3f3cd72da498c09e5f2b40d6c694ae8199f0974090c31918274b0e2d678c4ec8b6a23ea9f471b94f8ba8546e5a2db3e6c1efa1e47e0c28"} 02:49:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:49:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:49:28 INFO - Contents: 02:49:28 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2ac8583b9fea50d8edd37b9664040eeae92eb94d49e94f91ee337a0e1db710d415b5c000ebda58517c03f254001bd75d65b1880aa4aad838c83bd1c13c3394e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eda5d2a34985e5827d42edb673ac83e38c90f6c130710fd1794af85ea1cbbbe33c2b5cb3337f9333cdf64a72ca995fed03512da191d0e9626b2d9dd487d95c6c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4250ec25d2416add843324baf78a175796184f56466d980bf89ec08b9d96b29019fd94a56204ed70467402f9fd7329d8834d4a5a19f41eae7cb2abc332c0756", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af9c7754268abdcd3f3f3cd72da498c09e5f2b40d6c694ae8199f0974090c31918274b0e2d678c4ec8b6a23ea9f471b94f8ba8546e5a2db3e6c1efa1e47e0c28"} 02:49:28 INFO - Running post-run listener: copy_logs_to_upload_dir 02:49:28 INFO - Copying logs to upload dir... 02:49:28 INFO - mkdir: /builds/slave/test/build/upload/logs 02:49:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.167834 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-01 02:49:28.248225) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:28.253337) ========= 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=1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081 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/sha512/b2ac8583b9fea50d8edd37b9664040eeae92eb94d49e94f91ee337a0e1db710d415b5c000ebda58517c03f254001bd75d65b1880aa4aad838c83bd1c13c3394e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eda5d2a34985e5827d42edb673ac83e38c90f6c130710fd1794af85ea1cbbbe33c2b5cb3337f9333cdf64a72ca995fed03512da191d0e9626b2d9dd487d95c6c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4250ec25d2416add843324baf78a175796184f56466d980bf89ec08b9d96b29019fd94a56204ed70467402f9fd7329d8834d4a5a19f41eae7cb2abc332c0756", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af9c7754268abdcd3f3f3cd72da498c09e5f2b40d6c694ae8199f0974090c31918274b0e2d678c4ec8b6a23ea9f471b94f8ba8546e5a2db3e6c1efa1e47e0c28"} build_url:https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2ac8583b9fea50d8edd37b9664040eeae92eb94d49e94f91ee337a0e1db710d415b5c000ebda58517c03f254001bd75d65b1880aa4aad838c83bd1c13c3394e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eda5d2a34985e5827d42edb673ac83e38c90f6c130710fd1794af85ea1cbbbe33c2b5cb3337f9333cdf64a72ca995fed03512da191d0e9626b2d9dd487d95c6c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4250ec25d2416add843324baf78a175796184f56466d980bf89ec08b9d96b29019fd94a56204ed70467402f9fd7329d8834d4a5a19f41eae7cb2abc332c0756", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af9c7754268abdcd3f3f3cd72da498c09e5f2b40d6c694ae8199f0974090c31918274b0e2d678c4ec8b6a23ea9f471b94f8ba8546e5a2db3e6c1efa1e47e0c28"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:28.283099) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:28.283459) ========= 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=1f5564757af27664ca02d71f000001b0-1504244033.882995-618702081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004568 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:28.368851) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 02:49:28.369177) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 02:49:28.369599) ========= ========= Total master_lag: 0.30 =========