builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-072 starttime: 1504258102.62 results: success (0) revision: a3585c77e2b1bc5f5fea907e97762f7b47a12033 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:22.616513) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:22.619498) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:22.619839) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:22.648771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:22.649153) ========= 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=199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 02:28:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 229M=0s 2017-09-01 02:28:22 (229 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.194124 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:22.871888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:22.872278) ========= 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=199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014601 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:22.925939) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-01 02:28:22.926485) ========= 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=199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 02:28:22,987 truncating revision to first 12 chars 2017-09-01 02:28:22,987 Setting DEBUG logging. 2017-09-01 02:28:22,987 attempt 1/10 2017-09-01 02:28:22,987 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:24,077 attempt 1/10 2017-09-01 02:28:24,871 current task status: no status available at this point. state: STARTED 2017-09-01 02:28:24,872 sleeping for 9.00s (attempt 1/10) 2017-09-01 02:28:33,881 attempt 2/10 2017-09-01 02:28:34,295 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-01 02:28:34,717 unpacking tar archive at: mozilla-central-a3585c77e2b1/testing/mozharness/ program finished with exit code 0 elapsedTime=12.615663 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-01 02:28:35.561186) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:35.561571) ========= script_repo_revision: a3585c77e2b1bc5f5fea907e97762f7b47a12033 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:35.562017) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:35.562352) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:35.573676) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-01 02:28:35.573998) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:28:35 INFO - MultiFileLogger online at 20170901 02:28:35 in /builds/slave/test 02:28:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 02:28:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:28:35 INFO - {'append_to_log': False, 02:28:35 INFO - 'base_work_dir': '/builds/slave/test', 02:28:35 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:28:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:28:35 INFO - 'branch': 'Firefox-Non-PGO', 02:28:35 INFO - 'buildbot_json_path': 'buildprops.json', 02:28:35 INFO - 'code_coverage': False, 02:28:35 INFO - 'config_files': ('talos/linux_config.py',), 02:28:35 INFO - 'default_actions': ('clobber', 02:28:35 INFO - 'read-buildbot-config', 02:28:35 INFO - 'download-and-extract', 02:28:35 INFO - 'populate-webroot', 02:28:35 INFO - 'create-virtualenv', 02:28:35 INFO - 'install', 02:28:35 INFO - 'setup-mitmproxy', 02:28:35 INFO - 'run-tests'), 02:28:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:28:35 INFO - 'disable_ccov_upload': False, 02:28:35 INFO - 'disable_stylo': False, 02:28:35 INFO - 'download_minidump_stackwalk': True, 02:28:35 INFO - 'download_symbols': 'ondemand', 02:28:35 INFO - 'enable_stylo': False, 02:28:35 INFO - 'enable_webrender': False, 02:28:35 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:28:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:28:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:28:35 INFO - 'gecko_profile': False, 02:28:35 INFO - 'gecko_profile_interval': 0, 02:28:35 INFO - 'installer_path': 'installer.exe', 02:28:35 INFO - 'log_level': 'info', 02:28:35 INFO - 'log_name': 'talos', 02:28:35 INFO - 'log_to_console': True, 02:28:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:28:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:28:35 INFO - 'opt_config_files': (), 02:28:35 INFO - 'pip_index': False, 02:28:35 INFO - 'suite': 'dromaeojs-e10s', 02:28:35 INFO - 'system_bits': '32', 02:28:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:28:35 INFO - 'title': 'talos-linux64-ix-072', 02:28:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:28:35 INFO - 'use_talos_json': True, 02:28:35 INFO - 'verify': 'False', 02:28:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:28:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:28:35 INFO - 'work_dir': 'build'} 02:28:35 INFO - [mozharness: 2017-09-01 09:28:35.860847Z] Running clobber step. 02:28:35 INFO - Running pre-action listener: _resource_record_pre_action 02:28:35 INFO - Running main action method: clobber 02:28:35 INFO - rmtree: /builds/slave/test/build 02:28:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:28:36 INFO - Running post-action listener: _resource_record_post_action 02:28:36 INFO - [mozharness: 2017-09-01 09:28:36.841575Z] Finished clobber step (success) 02:28:36 INFO - [mozharness: 2017-09-01 09:28:36.841671Z] Running read-buildbot-config step. 02:28:36 INFO - Running pre-action listener: _resource_record_pre_action 02:28:36 INFO - Running main action method: read_buildbot_config 02:28:36 INFO - Using buildbot properties: 02:28:36 INFO - { 02:28:36 INFO - "project": "", 02:28:36 INFO - "product": "firefox", 02:28:36 INFO - "who": "archaeopteryx@coole-files.de", 02:28:36 INFO - "installer_path": "public/build/target.tar.bz2", 02:28:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:28:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:28:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 02:28:36 INFO - "stage_platform": "linux64", 02:28:36 INFO - "basedir": "/builds/slave/test", 02:28:36 INFO - "buildnumber": 1107, 02:28:36 INFO - "platform": "ubuntu64_hw", 02:28:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:28:36 INFO - "slavebuilddir": "test", 02:28:36 INFO - "taskId": "QrHudTwtR0yPJHMlZa-N-w", 02:28:36 INFO - "branch": "mozilla-central", 02:28:36 INFO - "script_repo_revision": "production", 02:28:36 INFO - "revision": "a3585c77e2b1bc5f5fea907e97762f7b47a12033", 02:28:36 INFO - "slavename": "talos-linux64-ix-072", 02:28:36 INFO - "repo_path": "mozilla-central" 02:28:36 INFO - } 02:28:36 INFO - Finding installer, test and symbols from parent task. 02:28:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QrHudTwtR0yPJHMlZa-N-w'}, attempt #1 02:28:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QrHudTwtR0yPJHMlZa-N-w'}, attempt #1 02:28:37 INFO - Task dependencies: QjwniFnmR7exVsWnUkO_3Q 02:28:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q'}, attempt #1 02:28:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:28:38 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.test_packages.json 02:28:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip 02:28:38 INFO - Running post-action listener: _resource_record_post_action 02:28:38 INFO - [mozharness: 2017-09-01 09:28:38.218033Z] Finished read-buildbot-config step (success) 02:28:38 INFO - [mozharness: 2017-09-01 09:28:38.218248Z] Running download-and-extract step. 02:28:38 INFO - Running pre-action listener: _resource_record_pre_action 02:28:38 INFO - Running main action method: download_and_extract 02:28:38 INFO - mkdir: /builds/slave/test/build/tests 02:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:38 INFO - trying https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.test_packages.json 02:28:38 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:38 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:38 INFO - Downloaded 989 bytes. 02:28:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:28:38 INFO - Using the following test package requirements: 02:28:38 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:28:38 INFO - u'common': [u'target.common.tests.zip'], 02:28:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:28:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:28:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:28:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:28:38 INFO - u'mozbase': [u'target.common.tests.zip'], 02:28:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:28:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:28:38 INFO - u'web-platform': [u'target.common.tests.zip', 02:28:38 INFO - u'target.web-platform.tests.tar.gz'], 02:28:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:28:38 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:28:38 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:38 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:38 INFO - Fetch https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip into memory 02:28:39 INFO - Content-Length response header: 54957508 02:28:39 INFO - Bytes received: 54957508 02:28:50 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:28:50 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:50 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:50 INFO - Fetch https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip into memory 02:28:52 INFO - Content-Length response header: 54957508 02:28:52 INFO - Bytes received: 54957508 02:29:04 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:04 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:04 INFO - Fetch https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.talos.tests.zip into memory 02:29:04 INFO - Content-Length response header: 14496650 02:29:04 INFO - Bytes received: 14496650 02:29:05 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:05 INFO - trying https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:29:05 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:05 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:06 INFO - Downloaded 62582204 bytes. 02:29:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:29:06 INFO - mkdir: /builds/slave/test/properties 02:29:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:29:06 INFO - Writing to file /builds/slave/test/properties/build_url 02:29:06 INFO - Contents: 02:29:06 INFO - build_url:https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:29:06 INFO - Running post-action listener: _resource_record_post_action 02:29:06 INFO - Running post-action listener: find_tests_for_verification 02:29:06 INFO - Running post-action listener: set_extra_try_arguments 02:29:06 INFO - [mozharness: 2017-09-01 09:29:06.346039Z] Finished download-and-extract step (success) 02:29:06 INFO - [mozharness: 2017-09-01 09:29:06.346136Z] Running populate-webroot step. 02:29:06 INFO - Running pre-action listener: _resource_record_pre_action 02:29:06 INFO - Running main action method: populate_webroot 02:29:06 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:29:06 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:29:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:29:06 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:29:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:29:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:06 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:06 INFO - 'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:29:06 INFO - 'tests': ['damp', 'tps']}, 02:29:06 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:06 INFO - 'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['damp', 'tps']}, 02:29:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:29:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:29:06 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:29:06 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:29:06 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:29:06 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:06 INFO - 'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:29:06 INFO - 'other-e10s': {'tests': ['a11yr', 02:29:06 INFO - 'ts_paint', 02:29:06 INFO - 'tpaint', 02:29:06 INFO - 'sessionrestore', 02:29:06 INFO - 'sessionrestore_many_windows', 02:29:06 INFO - 'sessionrestore_no_auto_restore', 02:29:06 INFO - 'tabpaint']}, 02:29:06 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['a11yr', 02:29:06 INFO - 'ts_paint', 02:29:06 INFO - 'tpaint', 02:29:06 INFO - 'sessionrestore', 02:29:06 INFO - 'sessionrestore_many_windows', 02:29:06 INFO - 'sessionrestore_no_auto_restore', 02:29:06 INFO - 'tabpaint']}, 02:29:06 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:29:06 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:29:06 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['bloom_basic_singleton']}, 02:29:06 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['bloom_basic']}, 02:29:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:29:06 INFO - 'tsvgr_opacity', 02:29:06 INFO - 'tart', 02:29:06 INFO - 'tscrollx', 02:29:06 INFO - 'tsvg_static']}, 02:29:06 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['tsvgx', 02:29:06 INFO - 'tsvgr_opacity', 02:29:06 INFO - 'tart', 02:29:06 INFO - 'tscrollx', 02:29:06 INFO - 'tsvg_static']}, 02:29:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:29:06 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:06 INFO - 'talos_options': ['--enable-stylo'], 02:29:06 INFO - 'tests': ['tp5o']}, 02:29:06 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:29:06 INFO - 'talos_options': ['--mitmproxy', 02:29:06 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:29:06 INFO - '--firstNonBlankPaint'], 02:29:06 INFO - 'tests': ['tp6_google', 02:29:06 INFO - 'tp6_youtube', 02:29:06 INFO - 'tp6_amazon', 02:29:06 INFO - 'tp6_facebook']}, 02:29:06 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:29:06 INFO - 'talos_options': ['--enable-stylo', 02:29:06 INFO - '--mitmproxy', 02:29:06 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:29:06 INFO - '--firstNonBlankPaint'], 02:29:06 INFO - 'tests': ['tp6_google', 02:29:06 INFO - 'tp6_youtube', 02:29:06 INFO - 'tp6_amazon', 02:29:06 INFO - 'tp6_facebook']}, 02:29:06 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:29:06 INFO - 'talos_options': ['--enable-stylo', 02:29:06 INFO - '--stylo-threads=1', 02:29:06 INFO - '--mitmproxy', 02:29:06 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:29:06 INFO - '--firstNonBlankPaint'], 02:29:06 INFO - 'tests': ['tp6_google', 02:29:06 INFO - 'tp6_youtube', 02:29:06 INFO - 'tp6_amazon', 02:29:06 INFO - 'tp6_facebook']}, 02:29:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:29:06 INFO - 'talos_options': ['--xperf_path', 02:29:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:06 INFO - 'tests': ['tp5n']}, 02:29:06 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:06 INFO - 'talos_options': ['--enable-stylo', 02:29:06 INFO - '--xperf_path', 02:29:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:06 INFO - 'tests': ['tp5n']}}} 02:29:06 INFO - Running post-action listener: _resource_record_post_action 02:29:06 INFO - [mozharness: 2017-09-01 09:29:06.360292Z] Finished populate-webroot step (success) 02:29:06 INFO - [mozharness: 2017-09-01 09:29:06.360381Z] Running create-virtualenv step. 02:29:06 INFO - Running pre-action listener: _resource_record_pre_action 02:29:06 INFO - Running main action method: create_virtualenv 02:29:06 INFO - Creating virtualenv /builds/slave/test/build/venv 02:29:06 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:29:06 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:29:06 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:29:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:29:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:29:07 INFO - Installing distribute.............................................................................................................................................................................................done. 02:29:07 INFO - Installing pip...............done. 02:29:07 INFO - Return code: 0 02:29:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:29:07 INFO - Reading from file tmpfile_stdout 02:29:07 INFO - Output received: 02:29:07 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:29:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x252ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, '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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', '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:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:29:07 INFO - Using env: {'DISPLAY': ':0', 02:29:07 INFO - 'HOME': '/home/cltbld', 02:29:07 INFO - 'LANG': 'en_US.UTF-8', 02:29:07 INFO - 'LANGUAGE': 'en_US:en', 02:29:07 INFO - 'LOGNAME': 'cltbld', 02:29:07 INFO - 'MAIL': '/var/mail/cltbld', 02:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:07 INFO - 'MOZ_NO_REMOTE': '1', 02:29:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:07 INFO - 'NO_EM_RESTART': '1', 02:29:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:07 INFO - 'PWD': '/builds/slave/test', 02:29:07 INFO - 'SHELL': '/bin/bash', 02:29:07 INFO - 'SHLVL': '1', 02:29:07 INFO - 'TERM': 'linux', 02:29:07 INFO - 'TMOUT': '86400', 02:29:07 INFO - 'USER': 'cltbld', 02:29:07 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 02:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:07 INFO - '_': '/tools/buildbot/bin/python'} 02:29:08 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:29:08 INFO - Downloading/unpacking pip>=1.5 02:29:08 INFO - Running setup.py egg_info for package pip 02:29:08 INFO - warning: no files found matching 'pip/cacert.pem' 02:29:08 INFO - warning: no files found matching '*.html' under directory 'docs' 02:29:08 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:29:08 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:29:08 INFO - Installing collected packages: pip 02:29:08 INFO - Found existing installation: pip 1.1 02:29:08 INFO - Uninstalling pip: 02:29:08 INFO - Successfully uninstalled pip 02:29:08 INFO - Running setup.py install for pip 02:29:08 INFO - warning: no files found matching 'pip/cacert.pem' 02:29:08 INFO - warning: no files found matching '*.html' under directory 'docs' 02:29:08 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:29:08 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:29:08 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:29:08 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:29:08 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:29:08 INFO - Successfully installed pip 02:29:08 INFO - Cleaning up... 02:29:08 INFO - Return code: 0 02:29:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:29:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x252ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, '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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', '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:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:29:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:29:08 INFO - Using env: {'DISPLAY': ':0', 02:29:08 INFO - 'HOME': '/home/cltbld', 02:29:08 INFO - 'LANG': 'en_US.UTF-8', 02:29:08 INFO - 'LANGUAGE': 'en_US:en', 02:29:08 INFO - 'LOGNAME': 'cltbld', 02:29:08 INFO - 'MAIL': '/var/mail/cltbld', 02:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:08 INFO - 'MOZ_NO_REMOTE': '1', 02:29:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:08 INFO - 'NO_EM_RESTART': '1', 02:29:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:08 INFO - 'PWD': '/builds/slave/test', 02:29:08 INFO - 'SHELL': '/bin/bash', 02:29:08 INFO - 'SHLVL': '1', 02:29:08 INFO - 'TERM': 'linux', 02:29:08 INFO - 'TMOUT': '86400', 02:29:08 INFO - 'USER': 'cltbld', 02:29:08 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 02:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:08 INFO - '_': '/tools/buildbot/bin/python'} 02:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:09 INFO - Downloading/unpacking psutil>=3.1.1 02:29:09 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:09 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:09 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:09 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:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:29:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:10 INFO - Installing collected packages: psutil 02:29:10 INFO - Running setup.py install for psutil 02:29:10 INFO - building 'psutil._psutil_linux' extension 02:29:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:29:11 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:11 INFO - building 'psutil._psutil_posix' extension 02:29:11 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:11 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:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:11 INFO - Successfully installed psutil 02:29:11 INFO - Cleaning up... 02:29:11 INFO - Return code: 0 02:29:11 INFO - Installing mozsystemmonitor==0.3 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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x252ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, '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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', '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', 'mozsystemmonitor==0.3'] 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 mozsystemmonitor==0.3 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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 02:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:11 INFO - '_': '/tools/buildbot/bin/python'} 02:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:29:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:29:12 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:12 INFO - Installing collected packages: mozsystemmonitor 02:29:12 INFO - Running setup.py install for mozsystemmonitor 02:29:12 INFO - Successfully installed mozsystemmonitor 02:29:12 INFO - Cleaning up... 02:29:13 INFO - Return code: 0 02:29:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x252ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, '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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', '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:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:29:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:29:13 INFO - Using env: {'DISPLAY': ':0', 02:29:13 INFO - 'HOME': '/home/cltbld', 02:29:13 INFO - 'LANG': 'en_US.UTF-8', 02:29:13 INFO - 'LANGUAGE': 'en_US:en', 02:29:13 INFO - 'LOGNAME': 'cltbld', 02:29:13 INFO - 'MAIL': '/var/mail/cltbld', 02:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:13 INFO - 'MOZ_NO_REMOTE': '1', 02:29:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:13 INFO - 'NO_EM_RESTART': '1', 02:29:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:13 INFO - 'PWD': '/builds/slave/test', 02:29:13 INFO - 'SHELL': '/bin/bash', 02:29:13 INFO - 'SHLVL': '1', 02:29:13 INFO - 'TERM': 'linux', 02:29:13 INFO - 'TMOUT': '86400', 02:29:13 INFO - 'USER': 'cltbld', 02:29:13 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 02:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:13 INFO - '_': '/tools/buildbot/bin/python'} 02:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:13 INFO - Downloading/unpacking jsonschema==2.5.1 02:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 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:14 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:14 INFO - warning: no files found matching '*.txt' 02:29:14 INFO - no previously-included directories found matching 'build' 02:29:14 INFO - no previously-included directories found matching 'dist' 02:29:14 INFO - no previously-included directories found matching '.git*' 02:29:14 INFO - Installing collected packages: jsonschema, functools32 02:29:14 INFO - Running setup.py install for functools32 02:29:14 INFO - warning: no files found matching '*.txt' 02:29:14 INFO - no previously-included directories found matching 'build' 02:29:14 INFO - no previously-included directories found matching 'dist' 02:29:14 INFO - no previously-included directories found matching '.git*' 02:29:14 INFO - Successfully installed jsonschema functools32 02:29:14 INFO - Cleaning up... 02:29:14 INFO - Return code: 0 02:29:14 INFO - Installing functools32==3.2.3-2 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', '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 0x21bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x252ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, '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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', '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', 'functools32==3.2.3-2'] 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 functools32==3.2.3-2 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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 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 - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:29:14 INFO - Cleaning up... 02:29:14 INFO - Return code: 0 02:29:14 INFO - Installing blobuploader==1.2.4 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', '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 0x21bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x252ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, '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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', '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', 'blobuploader==1.2.4'] 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 blobuploader==1.2.4 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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 02:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:14 INFO - '_': '/tools/buildbot/bin/python'} 02:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:15 INFO - Downloading/unpacking blobuploader==1.2.4 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:16 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:16 INFO - Downloading docopt-0.6.1.tar.gz 02:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:16 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:16 INFO - Running setup.py install for blobuploader 02:29:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:16 INFO - Running setup.py install for requests 02:29:17 INFO - Running setup.py install for docopt 02:29:17 INFO - Successfully installed blobuploader requests docopt 02:29:17 INFO - Cleaning up... 02:29:17 INFO - Return code: 0 02:29:17 INFO - Installing None 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', '--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 0x21bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x252ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, '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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', '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', '--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:17 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: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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:17 INFO - Running setup.py (path:/tmp/pip-iqcOHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:17 INFO - Running setup.py (path:/tmp/pip-HwyYIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:17 INFO - Running setup.py (path:/tmp/pip-JXXE31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:17 INFO - Running setup.py (path:/tmp/pip-sbQF7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:17 INFO - Running setup.py (path:/tmp/pip-4no90r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:18 INFO - Running setup.py (path:/tmp/pip-3IMOQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:18 INFO - Running setup.py (path:/tmp/pip-Dp0iOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:18 INFO - Running setup.py (path:/tmp/pip-rUhVFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:18 INFO - Running setup.py (path:/tmp/pip-4WmJt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:18 INFO - Running setup.py (path:/tmp/pip-BfYQzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:18 INFO - Running setup.py (path:/tmp/pip-t1VoSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:18 INFO - Running setup.py (path:/tmp/pip-L1q9QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:18 INFO - Running setup.py (path:/tmp/pip-iZlcc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:18 INFO - Running setup.py (path:/tmp/pip-EpL7Ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:18 INFO - Running setup.py (path:/tmp/pip-M2jKZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:18 INFO - Running setup.py (path:/tmp/pip-Io7TnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:18 INFO - Running setup.py (path:/tmp/pip-pVLUqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:18 INFO - Running setup.py install for manifestparser 02:29:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:18 INFO - Running setup.py install for mozcrash 02:29:19 INFO - Running setup.py install for mozdebug 02:29:19 INFO - Running setup.py install for mozdevice 02:29:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for mozfile 02:29:19 INFO - Running setup.py install for mozhttpd 02:29:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for mozinfo 02:29:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for mozInstall 02:29:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for mozleak 02:29:19 INFO - Running setup.py install for mozlog 02:29:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for moznetwork 02:29:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for mozprocess 02:29:20 INFO - Running setup.py install for mozprofile 02:29:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Running setup.py install for mozrunner 02:29:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Running setup.py install for mozscreenshot 02:29:20 INFO - Running setup.py install for moztest 02:29:20 INFO - Running setup.py install for mozversion 02:29:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 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', '--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 0x21bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x252ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, '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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', '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', '--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 --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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 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-nQlFT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:20 INFO - Running setup.py (path:/tmp/pip-iaMuCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:21 INFO - Running setup.py (path:/tmp/pip-f9jFt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:21 INFO - Running setup.py (path:/tmp/pip-OopNAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:21 INFO - Running setup.py (path:/tmp/pip-482MGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:21 INFO - Running setup.py (path:/tmp/pip-v6a0uW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:21 INFO - Running setup.py (path:/tmp/pip-soLj6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:21 INFO - Running setup.py (path:/tmp/pip-0D2eWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:21 INFO - Running setup.py (path:/tmp/pip-OMOQDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:21 INFO - Running setup.py (path:/tmp/pip-fFQuJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:21 INFO - Running setup.py (path:/tmp/pip-xWyvdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:21 INFO - Running setup.py (path:/tmp/pip-FIbJIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:21 INFO - Running setup.py (path:/tmp/pip-kGw42h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:21 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:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:21 INFO - Running setup.py (path:/tmp/pip-jh97cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:21 INFO - Running setup.py (path:/tmp/pip-KwUQtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:21 INFO - Running setup.py (path:/tmp/pip-Mf2Y2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:21 INFO - Running setup.py (path:/tmp/pip-ADhJS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:22 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:22 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:22 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:22 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:22 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:22 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:22 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:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:23 INFO - Downloading blessings-1.6.tar.gz 02:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:23 INFO - Installing collected packages: blessings 02:29:23 INFO - Running setup.py install for blessings 02:29:23 INFO - Successfully installed blessings 02:29:23 INFO - Cleaning up... 02:29:23 INFO - Return code: 0 02:29:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:23 INFO - Reading from file tmpfile_stdout 02:29:23 INFO - Current package versions: 02:29:23 INFO - argparse == 1.2.1 02:29:23 INFO - blessings == 1.6 02:29:23 INFO - blobuploader == 1.2.4 02:29:23 INFO - docopt == 0.6.1 02:29:23 INFO - functools32 == 3.2.3-2 02:29:23 INFO - jsonschema == 2.5.1 02:29:23 INFO - manifestparser == 1.1 02:29:23 INFO - mozInstall == 1.13 02:29:23 INFO - mozcrash == 1.0 02:29:23 INFO - mozdebug == 0.1 02:29:23 INFO - mozdevice == 0.51 02:29:23 INFO - mozfile == 1.2 02:29:23 INFO - mozhttpd == 0.7 02:29:23 INFO - mozinfo == 0.10 02:29:23 INFO - mozleak == 0.1 02:29:23 INFO - mozlog == 3.5 02:29:23 INFO - moznetwork == 0.27 02:29:23 INFO - mozprocess == 0.25 02:29:23 INFO - mozprofile == 0.28 02:29:23 INFO - mozrunner == 6.13 02:29:23 INFO - mozscreenshot == 0.1 02:29:23 INFO - mozsystemmonitor == 0.3 02:29:23 INFO - moztest == 0.8 02:29:23 INFO - mozversion == 1.4 02:29:23 INFO - psutil == 3.1.1 02:29:23 INFO - requests == 1.2.3 02:29:23 INFO - wsgiref == 0.1.2 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/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 0x21bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x252ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, '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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', '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/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:23 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: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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 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 - 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:23 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:23 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:23 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:23 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:23 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:23 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:23 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:23 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:23 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:29:23 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:23 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:23 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:23 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 - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:29:25 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:25 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:25 INFO - Installing collected packages: simplejson 02:29:25 INFO - Running setup.py install for simplejson 02:29:25 INFO - building 'simplejson._speedups' extension 02:29:25 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:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 02:29:26 INFO - Successfully installed simplejson 02:29:26 INFO - Cleaning up... 02:29:26 INFO - Return code: 0 02:29:26 INFO - Installing jsonschema 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', '--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 0x21bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x252ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, '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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', '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', '--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:26 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: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': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 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): jsonschema in ./venv/lib/python2.7/site-packages 02:29:26 INFO - Cleaning up... 02:29:26 INFO - Return code: 0 02:29:26 INFO - Running post-action listener: _resource_record_post_action 02:29:26 INFO - Running post-action listener: _start_resource_monitoring 02:29:26 INFO - Starting resource monitoring. 02:29:26 INFO - [mozharness: 2017-09-01 09:29:26.416684Z] Finished create-virtualenv step (success) 02:29:26 INFO - [mozharness: 2017-09-01 09:29:26.417140Z] Running install step. 02:29:26 INFO - Running pre-action listener: _resource_record_pre_action 02:29:26 INFO - Running main action method: install 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 - Detecting whether we're running mozinstall >=1.0... 02:29:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:26 INFO - Reading from file tmpfile_stdout 02:29:26 INFO - Output received: 02:29:26 INFO - Usage: mozinstall [options] installer 02:29:26 INFO - Options: 02:29:26 INFO - -h, --help show this help message and exit 02:29:26 INFO - -d DEST, --destination=DEST 02:29:26 INFO - Directory to install application into. [default: 02:29:26 INFO - "/builds/slave/test"] 02:29:26 INFO - --app=APP Application being installed. [default: firefox] 02:29:26 INFO - mkdir: /builds/slave/test/build/application 02:29:26 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:26 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:43 INFO - Reading from file tmpfile_stdout 02:29:43 INFO - Output received: 02:29:43 INFO - /builds/slave/test/build/application/firefox/firefox 02:29:43 INFO - Running post-action listener: _resource_record_post_action 02:29:43 INFO - [mozharness: 2017-09-01 09:29:43.300412Z] Finished install step (success) 02:29:43 INFO - [mozharness: 2017-09-01 09:29:43.300661Z] Running setup-mitmproxy step. 02:29:43 INFO - Running pre-action listener: _resource_record_pre_action 02:29:43 INFO - Running main action method: setup_mitmproxy 02:29:43 INFO - Skipping: mitmproxy is not required 02:29:43 INFO - Running post-action listener: _resource_record_post_action 02:29:43 INFO - [mozharness: 2017-09-01 09:29:43.301779Z] Finished setup-mitmproxy step (success) 02:29:43 INFO - [mozharness: 2017-09-01 09:29:43.301998Z] Running run-tests step. 02:29:43 INFO - Running pre-action listener: _resource_record_pre_action 02:29:43 INFO - Running pre-action listener: _set_gcov_prefix 02:29:43 INFO - Running main action method: run_tests 02:29:43 WARNING - Try message not found. 02:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:43 INFO - Python 2.7.3 02:29:43 INFO - Return code: 0 02:29:43 INFO - grabbing minidump binary from tooltool 02:29:43 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:43 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 0x252f090>, '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 0x252fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2529f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:43 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:43 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:43 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:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:43 INFO - Return code: 0 02:29:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:29:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:43 INFO - ENV: RUST_BACKTRACE is now full 02:29:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:29:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-072 --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:43 INFO - Using env: {'DISPLAY': ':0', 02:29:43 INFO - 'HOME': '/home/cltbld', 02:29:43 INFO - 'LANG': 'en_US.UTF-8', 02:29:43 INFO - 'LANGUAGE': 'en_US:en', 02:29:43 INFO - 'LOGNAME': 'cltbld', 02:29:43 INFO - 'MAIL': '/var/mail/cltbld', 02:29:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:43 INFO - 'MOZ_NO_REMOTE': '1', 02:29:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:43 INFO - 'NO_EM_RESTART': '1', 02:29:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:43 INFO - 'PWD': '/builds/slave/test', 02:29:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:43 INFO - 'RUST_BACKTRACE': 'full', 02:29:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:29:43 INFO - 'SHELL': '/bin/bash', 02:29:43 INFO - 'SHLVL': '1', 02:29:43 INFO - 'TERM': 'linux', 02:29:43 INFO - 'TMOUT': '86400', 02:29:43 INFO - 'USER': 'cltbld', 02:29:43 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 02:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:43 INFO - '_': '/tools/buildbot/bin/python'} 02:29:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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:43 INFO - mozversion application_buildid: 20170901083953 02:29:43 INFO - mozversion application_changeset: a3585c77e2b1bc5f5fea907e97762f7b47a12033 02:29:43 INFO - mozversion application_display_name: Nightly 02:29:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:43 INFO - mozversion application_name: Firefox 02:29:43 INFO - mozversion application_remotingname: firefox 02:29:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:29:43 INFO - mozversion application_vendor: Mozilla 02:29:43 INFO - mozversion application_version: 57.0a1 02:29:43 INFO - mozversion platform_buildid: 20170901083953 02:29:43 INFO - mozversion platform_changeset: a3585c77e2b1bc5f5fea907e97762f7b47a12033 02:29:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:29:43 INFO - mozversion platform_version: 57.0a1 02:29:43 INFO - using testdate: 1504258183 02:29:43 INFO - actual date: 1504258183 02:29:43 INFO - starting webserver on 'localhost:36348' 02:29:43 INFO - SUITE-START | Running 2 tests 02:29:43 INFO - TEST-START | dromaeo_css 02:29:43 INFO - Initialising browser for dromaeo_css test... 02:29:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36348/getInfo.html -profile /tmp/tmpumv0AM/profile 02:29:43 INFO - TEST-INFO | started process 3568 (/builds/slave/test/build/application/firefox/firefox http://localhost:36348/getInfo.html) 02:29:52 INFO - TEST-INFO | 3568: 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 - 'TERM': 'linux', 02:29:52 INFO - 'TMOUT': '86400', 02:29:52 INFO - 'USER': 'cltbld', 02:29:52 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 02:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:52 INFO - '_': '/tools/buildbot/bin/python'} 02:29:52 INFO - TEST-INFO | started process 3756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpumv0AM/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 3756 | 02:29:53 INFO - PID 3756 | (/builds/slave/test/build/application/firefox/firefox:3808): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:29:53 INFO - PID 3756 | 02:30:03 INFO - PID 3756 | RSS: Main: 168284160 02:30:03 INFO - PID 3756 | 02:33:02 INFO - PID 3756 | Cycle 1(1): loaded http://localhost:36348/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36348/tests/dromaeo/cssquery-ext.html) 02:33:03 INFO - PID 3756 | RSS: Main: 171331584 02:33:03 INFO - PID 3756 | 02:36:05 INFO - PID 3756 | Cycle 1(1): loaded http://localhost:36348/tests/dromaeo/cssquery-ext.html (next: http://localhost:36348/tests/dromaeo/cssquery-jquery.html) 02:36:06 INFO - PID 3756 | RSS: Main: 166907904 02:36:06 INFO - PID 3756 | 02:39:07 INFO - PID 3756 | Cycle 1(1): loaded http://localhost:36348/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36348/tests/dromaeo/cssquery-mootools.html) 02:39:08 INFO - PID 3756 | RSS: Main: 170037248 02:39:08 INFO - PID 3756 | 02:42:10 INFO - PID 3756 | Cycle 1(1): loaded http://localhost:36348/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36348/tests/dromaeo/cssquery-prototype.html) 02:42:10 INFO - PID 3756 | RSS: Main: 168521728 02:42:10 INFO - PID 3756 | 02:45:14 INFO - PID 3756 | Cycle 1(1): loaded http://localhost:36348/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36348/tests/dromaeo/cssquery-yui.html) 02:45:15 INFO - PID 3756 | RSS: Main: 169906176 02:45:15 INFO - PID 3756 | 02:48:16 INFO - PID 3756 | Cycle 1(1): loaded http://localhost:36348/tests/dromaeo/cssquery-yui.html (next: http://localhost:36348/tests/dromaeo/cssquery-dojo.html) 02:48:17 INFO - PID 3756 | RSS: Main: 171925504 02:48:17 INFO - PID 3756 | 02:48:17 INFO - PID 3756 | __start_tp_report 02:48:17 INFO - PID 3756 | _x_x_mozilla_page_load 02:48:17 INFO - PID 3756 | _x_x_mozilla_page_load_details 02:48:17 INFO - PID 3756 | |i|pagename|runs| 02:48:17 INFO - PID 3756 | |0;dojo.html;3313.155145437913;3336.733061355091;3338.1487720631235;3357.479590663448;3368.528406023157;6399.104125422433;6413.13422687937;6436.903446448307;6453.967730161348;6456.7094480748365;6354.396332348431;6424.100625912364;6448.451881590058;6461.418472337488;6473.708683109259;5859.501942334907;5895.823125306237;5918.852028699295;5941.613795103326;6076.4834989025985;6010.218671572689;6041.063635136561;6042.033274676052;6060.757569697207;6073.210482637251;5821.912671309934;5989.700514974255;6010.639361638316;6097.993831017877;6105.6947152642415;5866.178734977107;5875.294964604275;5950.613210141327;5959.374265702082;6011.639301641915;5883.205767221413;5921.940780592225;5971.820845374646;5978.461938425563;6015.248093988252;6349.36506349366;6373.044043393482;6377.936220637781;6425.807225783234;6548;6924.896126558106;6990.685419156151;6998.75504357345;7004.789856304319;7044.718211271543;6977.546459480168;7071.36357727807;7076.964615176943;7078.221395646475;7086.078809754699;6454.257760357619;6461.483081353481;6501.512386570978;6561.573497722633;6582.012698095228;6973.232944376115;7073.787786366417;7078.964605176941;7080.362767350963;7086.893696594555;6718.260991290954;6760;6793.422559082534;6799.252082270947;6852.177738671292;6975.720971161097;6981.3367730065565;7000.229974702778;7016.473764467685;7042.753503627348;6872.69072891721;6943.79168624942;6953.721851126;6971.954206868909;6995.440364770806;6900.896486552706;6913.5506192097355;6924.549904256207;7007.754728584475;7021.403180729593;5307.548858347083;5377.300950876439;5392.811251606254;5459.989901868167;5511.751971161227;4700.835470758507;4789.042191561702;4849.2726091086615;4859.902801943941;4885.7312847793;6062.090686397076;6145.754169833246;6151.7539298428455;6171.907421388682;6172.166757487682;6949.652517374212;6989.1962424321855;7019.403350715144;7054.964725176341;7076.504644674823;5871.941280587225;5925.911111333334;5942.049272116527;5959.880802384015;6125.908111378333;5918.053111502139;5933.406659334032;6017.969910150421;6022;6026.6986650667295;6728.495362847806;6775.186977562724;6788.864222715518;6802.931970680329;6820.965895170492;6966.094407727166;6992.860142797217;7063.081799366255;7075.292470752884;7083.7520686775715;6703.229128650072;6728.764493242908;6803.42170915458;6819.0794242778575;6829.795106146824;6649.567778094409;6752.493562982698;6754.662266886734;6834.829129271808;6928.4803639726215;6828.112345395266;6923.619200943795;6928.861422771617;6944.201416837129;6961.930380696128;6679.398854103154;6689.565178263398;6694.196696396463;6709.127813384229;6765.86468270622;6734.831629209309;6776.085228494288;6795.762148324785;6834.521583489108;6840.41856442208;6360.109584658059;6431.5819471734185;6665.133532640726;6716.361945615159;6758.986152077227;6699.732010719516;6713.865722685617;6762.188537375547;6884.4148247397525;6894.551854129466;6061.11998341753;6530.118434011348;6609.537332386687;6638;6641.701123449553 02:48:17 INFO - PID 3756 | |1;ext.html;737.395335824624;819.3445243804956;840.2815592668269;841.5792103948025;1105.5964572930882;29181.56227656584;40166.799166004166;41203;41241.38137927934;41306.7934660327;40644.18711625773;40948.181036379254;41045.38431923516;41165;41193.7940310298;7508.81227969301;7697.26875946787;7698.307152356287;7699.923000769976;7699.923000769976;7580.507267027653;7639.19788422216;7646.082470103595;7672.194419585947;7675.731349402772;7667.501612395205;7718.7684369468725;7726.111497177812;7737.72917947872;7741.8838717419285;7660.6976813941765;7692.846143077135;7709.7687069388285;7712;7723.150453450116;7539.283768042027;7582.696692132308;7588.620568971584;7591.848163036709;7592.202818704067;35826;36026.63973360259;36042.81978590116;36077.63922360769;36119.63880361215;3543.787372757643;3586.9820650896577;3588.3540962626716;3588.4976103345552;3590.2819436112622;30473.93341233046;30485.237869053228;30515;30572.235694107825;30654;4886.389201349832;5075.923861142086;5076.36545431821;5116.539511443988;5121.103806833789;185362;187611;187784.99999999863;187817;188693.05653471782;44638.33042504365;44682.10635787266;44697.55302446934;44874.55125448769;44881;48202;48237.27644085341;48254;48331.27503087457;48384.75807620939;12597.11820172579;12778;13984.720305594035;14211.573652790434;14236.715265694627;11716.297022178696;11723.35521546323;11724.237924534878;11726;11779.234349767237;13372.866271337161;13434.193948363129;13474.461021559224;13491.190528568317;13606.86393136056;5592.161175823578;5641.182028605829;5671.489565939085;5679.375268720438;5703.201551782754;1006.7332156978407;1014.1278500489573;1014.9492525373702;1018.8369860822232;1027.198784947742;22135.889320553295;22184.889075554518;22189.223377181723;22555.774442255697;22808.315750527512;1116.8827273136367;1118.9664310070712;1130.5760339872547;1131.196850236325;1131.7906187355366;11975.28148311104;11996.58011969577;12004.639860804189;12049.87950120505;12064.396780161132;217968;219249;219997;220038;220209;49982.25026624603;49987.75006124946;50225.24662130071;50505;50670.49329506732;1412.8869690424742;1448.160067161044;1448.8116544849313;1455.6215384000025;1459.1463993563673;22077.116915323848;22131.44671383164;22181.889090554443;22353.888230558743;22646.433839153495;35570;35618.821905890305;35662.465063024596;35694.4645830318;35739.28521429609;185544;186096;186180;186369;186672;29521;29874.402511950073;29958.700412996463;32117.197070073435;32307.19232019218;41178.58821411747;41465.792671037656;41470.37794433146;41532.169356612096;41954.580454196286;30833.383332333677;30855.69144308528;31189.8440507796;31359.37281254408;31518.684813152493;30789.692103078683;30846.69153308438;30874.845625771726;31147.688523115386;31298.843505782326;35033.474497883064;35170.29659406746;35249.47125793166;35429.46855797217;35483.11292217612;30247.395052099277;30504.389912201186;30740;30773.38453230878;30972.845135774176;22851.42871428161;22910.770892290864;23140.652890206322;24160;25435.6184657234 02:48:17 INFO - PID 3756 | |2;jquery.html;555.2310050579946;567.492094575355;568.9061304884694;575.3268675649489;588.2029849553854;21714.34856954292;22522.324330270076;22564.323070307895;22582.88708556459;23404.648930266016;25020.374490637743;25827.225183244533;25900.35249118773;25904.48191036178;25910.870445647794;6506.772262970797;6645.468362530986;6656.733730650768;6661.700223489955;6665.966670166642;6099.9085013724825;6489.643069631168;6689.7658581949645;6694.330566943291;6694.430973367245;6651;6667.833304167386;6670.59976401415;6678.699458524354;6687.632180230085;6646.667666616674;6655.301193374698;6668.833279168011;6671.032700258435;6679.866402671943;6196.81409557714;6206.534509911775;6214.347493513161;6225.595336303126;6227.097070924736;27755.306117347027;28204.294892627848;28586.714132858822;28918.855405722836;29150.85424572885;2044.4071219346372;2095.0467537270506;2095.685647152939;2101.0125241136643;2102.6320393931155;19269.22923083089;19909.203631854707;19911.402657920287;19943.601127977505;19949.202031918707;2411.2525117213545;2427.725444141822;2447.8041756659422;2469.9135530256353;2519.748025197484;135484;138803;138953;139832.99999999898;140037;14188.290585470686;14281.571552853431;14317.923901034292;14811.111333320036;14933.626659333386;22835.08659653595;22837.657435138488;22858.314250572508;22898.65652015221;22977.655335169984;9398;9569.904300957041;9573.234141268682;9778.65774697882;9817.558209880572;7238.674259658328;8825.249853762502;8853.955730221307;8862.911370886337;8893.955530222307;8032.236937490929;8992.582818528917;9064.184223419923;9065.229455496225;9345.15893569582;2642.5507663697217;2717.4293398386167;2722.060888993294;2800.7791040303814;2818.5208514552573;859.5100792548312;891.6968230801558;893.7006102955453;930.330162283155;986.3391527676474;19241.807581924;19304.613907722047;19450.902745486183;19952.50118747043;20128.59742805165;1036.5853658536646;1044.8850626431085;1048.2924026282228;1048.3133547526381;1054.8945105489543;10633.46832658364;10873.673789786319;10890.782184356269;10919.890801092046;11048;479941;500190;508552;509965;511630;121087.39456302662;122694;124541.37729311477;125706;126619.36690316674;1213.1629175868622;1217.8112392579164;1257.704439456727;1269.847618285793;1270.2188154285234;21228;21266.468338291048;21337.146514139262;21346.786532135102;21871.343859684235;56978.435970380604;57804.71097644485;58627.70686146542;58812.411875882404;60147.69926150341;76565;77559.61220193862;77892.61053694696;78116;78597.60701196457;11931;12425.62723118308;13844;13922.791158132433;14529;22761.88619056894;23110.42223944415;23144;23151.421214469774;23177.884110580013;17820.643587128445;17934.91032544829;18943.33698320552;18974.335898243495;18976.335828245945;17248.74126888123;17420.651586968444;17534.561635959202;17562.29750809953;17821.554461138574;18636.813631863508;18962.810371896103;19005.239790408228;19037.619247615246;19053.14260858237;14482.710345793235;14629.92685036568;14765.335559900044;14772.18752968597;14780.852191477947;18345;19700.605987879877;19723.80276197277;19726.704099438808;19739.210431582575 02:48:17 INFO - PID 3756 | |3;mootools.html;1334.2794890758992;1373.8420081690601;1376.4494202319077;1379.9586012419625;1382.6958069224768;14483.855161448382;15090;15116.773248401269;15151.409102044292;15179.848201517996;15901.840981590181;16149.43476978306;16166.595835104128;16199.83800161998;16260.105694186845;2702.5946108083845;2755.738204870534;2756.476269508797;2757.8483183424905;2758.889644414231;2715.2804506805714;2844.7724182065385;2845.046909285392;2846.6014757933904;2849.0455697341417;2790.2745286225627;2792.2879665685223;2822.957655635167;2826.886924523027;2829.3351062500296;2754.600582915466;2771.459565384751;2772.0436449424915;2782.2348854065194;2795.175423250146;2681.2090433322023;2682.1551211368355;2694.191742477249;2705.742954419327;2708.6614173228345;14923;15204.46784362542;15244.923775381052;15316.310766015557;15354;7860.135385107634;8140.308073813733;8174.959125204396;8183.222593853575;8191.713290034821;5382;5543.057680194336;5544.750486228089;5617.101263797814;5640.464155905182;1928.3732786844332;1931.4785008047888;1934.1683076277195;1976.3675623800389;1990.7511561054869;26722.5991610126;26939.999999999804;26983.05559305415;26989.865050674816;26999.190024299303;6794.626295553693;6817.829554261183;6846.144231971004;6960.338767817049;7062.563415621602;17519.64960700779;17585.560360991076;17595;17610.64778704419;17615.207315670825;7867.449278550561;8015.278624923785;8027;8033.357331413473;8041.7989550260545;7261.745838895613;7290.927090729131;7411.184769675331;7417.369523590555;7417.666205020787;5210.947890521122;5405.324334458192;5438.45615438453;5440.619156659075;5455.645383050089;1505.2925125191143;1531.1808182622244;1531.9004264722757;1537.8385269546761;1540.3145600208024;884.4118661090303;917.4449458077901;928.6285485805597;938.7043081429327;939.5490164720835;7793.883091753628;7875.84248315042;7905.249001344863;7965.362770978308;8027.678892844221;714.8927660850914;990.7912346936725;1014.5130337438071;1026.7741096958657;1026.9794604107876;5354.464553544614;5478.643888147258;5519.172124181405;5646.350669672958;5807.448292412214;21447.571048578942;21647.242346517796;21966.89016554907;22200.11199552032;22403.775962240168;20879.582408351747;21769.673454898188;21918.45203869884;21965.34103976883;22251.55496890053;11240.100791936626;11641.079221928456;11682.357470339213;11690.88309116915;11764.235324703866;8714.128587141235;8804.779880503038;8806.339524535557;8915.598798053974;8946.463212207289;17851.910740446216;18630.813691863452;19184.90407547953;19335;19452.902735486234;18594.163262653485;18971.62056758885;19325.226990920204;19739.703904441732;19811.20755169777;11672.416379180837;11955.342456164994;11975.580854670045;11990.880091198977;12035.75928481408;13980.44078236859;14018.158910464997;14128;14143.858561414254;14148;11639.010684091947;11681.474333655176;11818.527258909548;11923.403829808649;12008.09939254577;11300.660980170607;11303.434828258718;11440.771184576428;11555.306681599131;11657.183997120459;9216.308776841897;9315.813683726152;9323.953380233055;9338.95330523343;9373.109554592304;7272.854542909218;7328.890066649111;7356.154042285206;7358.337749602561;7369.37360324357;7168.2831716827895;7184.066071410683;7292.489525733148;7294.781156565312;7339.522931009467 02:48:17 INFO - PID 3756 | |4;prototype.html;495.7665715949646;497.55956505915367;531.1819797511832;533.9012282727696;536.5090941788264;6307.274663413708;6397.104405383244;6523.4455071318935;6526.3031611780925;6559.376859198374;6249.5000399968;6259.280182778982;6264.342244064376;6295.213098362705;6368.535236895516;5031.773570189332;5042.445331013586;5050.949490505094;5055.317532133152;5118.923216151742;4915.041566894457;5015.673981191211;5032.672876263031;5091.898162036757;5103.953689493664;4993.151164302078;5022.522860328263;5091.6690415123085;5103.23451482277;5111.923321150186;5001.624878134155;5042.470540593258;5068.518490743373;5073.568746656539;5088.287639730441;4521.841735539273;4565.52062033485;4577.665390650792;4583.449986001668;4589.334546490773;6302.842428939267;6317.684115794192;6323.8735225295695;6347.301796802348;6363.204599425072;5687.033204355228;5782.479576838105;5813.360530341659;5848.2689663792025;5864.266966629171;6618.305077966792;6726.529142959995;6731.451766093777;6757.763478278286;6771.93455995926;4925.162722337175;4932.161532539477;4953.182724850395;4979.601631869478;5003.849884503435;7057.153141622935;7077.327653872874;7078.186008609025;7122.216556178868;7139.785806425815;5055.974720126376;5069.8732531687;5072.873178170576;5083.212102124176;5094.949050509522;5137.409197942285;5147.356580427447;5159.380874295109;5163.690178589297;5208.921866171935;5801.680907550125;5815.011448053841;5816.592838501264;5843.7501561898125;5848.561357898175;6644.700988455531;6679.933200668028;6690.464762818963;6737.157855268091;6802.945543440775;1651.7687523746686;1686.2243368050833;1717.931282748676;1736.175316724571;1789.3647755047032;1203.3441774233063;1212.859911683015;1228.993855030737;1241.85097788266;1248.095131030014;5878.382769809194;5886.440788125121;5905.202797622352;5908.734106965111;5952.940470595238;6717.798466046026;6755.2906944771075;6806.523543351917;6850.780133337324;6906.482013848981;5880.147378630075;5903.35063143054;5920.5559583031445;5947.691507868262;5980.023284515748;3629.4011488104693;3633.800140992271;3649.9635003649623;3659.4144936810517;3718.8326525306425;6662.5003124765835;6705.899411508832;6727.831804204934;6745.392914637706;6825.35159159879;7118.508522017252;7143.571385716874;7144.678489467987;7162.570245785269;7205.459590530626;6553.574017688835;6688.799336019927;6692.59844409337;6694.364035416627;6694.564853284521;6797.252302246848;6802.387785099365;6859.828504287433;6924.4114250287275;6930.341617546526;6757.087793148059;6811.591304521745;6852.554583952027;6855.862882742417;6912.619805910723;6596.406323430914;6630.7679231226675;6665.233498147775;6722.596644201363;6752.628605426552;5034.421041580265;5064.82273120439;5066.949330506648;5079.2888995539915;5102.540771330598;6720.731170753115;6804.55770374924;6957;6978.057962175041;7024.367806897404;6334.271558770801;6523.869522609426;6663.900041499478;6692.464602831859;6704.4636429084585;6579.868402631825;6589.143411356493;6604.471642268703;6664.166979127609;6727.394534491919;6607.933920660732;6614.338566143347;6648.16897887764;6666.266710661726;6704.7318107277115;6644.90032649501;6740.820356437505;6746.730130794714;6789.524733268821;6822.692978816064;6475.579087359495;6496.577722448026;6517.73929042833;6557;6597.736090556324 02:48:17 INFO - PID 3756 | |5;yui.html;902.823949329881;914.2320450821309;925.3753716241536;929.1034152043277;941.7410212191648;17880.91059544702;18942.24231030759;18974.051297435126;18985.43043708691;21357.57284854302;19593.608127837433;20193;20242.089105990228;20279;20331.796682033175;921.548441263781;946.8437707778243;951.3578334624135;951.3863558005082;951.6431338248157;988.214369576185;989.0060489208336;989.3272574649236;989.9208063354914;996.6013594562161;993.3146129170885;1032.3186696780126;1033.8035773203105;1035.4926086217736;1035.5133087448887;1012.6354512375539;1013.908748212657;1013.989860101397;1014.1075853249167;1015.6546774096805;751.8007727952097;753.073719325233;753.506453273107;753.8454616803542;753.9811504712371;18361.7245741314;18745.156467958976;18905.338313158973;18922.3377181798;20012.89993550023;20765.792342076536;20895.5820883583;21017.5796484071;21341.893290533604;21508.139674413003;16305.347786088661;17063.14684265794;17216.225269862887;17287.13564321779;17615.207315670825;3756.906077348061;3791.090138366772;3791.2607041626893;3795.1460921292764;3795.582485926546;122285.38857305745;124578.37710811388;126015.36992315072;127267.36366318201;127362;16844.831551684572;17239.22423490946;17521.29914803419;17564.91217543904;17687.115644217738;30375.544366834514;30755.384892302027;30778.230544236125;30791.38417231643;31449;7485.737999170003;7830.92169078302;8731.432456890281;9145.496997665192;9206.125418085272;3532.6643968822923;3608.4587311903424;3614.566252049771;3615.7830530168276;3619.2580520993133;5303.522682958553;5371.274877891435;5383;5447.319085114361;5469.699166545799;1079;1086.9891301086889;1106.3472551194834;1109.6393672056452;1110.4114819145866;669.1134247122485;685.715428097344;686.989695154573;701.0570782297904;716.659586696315;11540.538378464767;11750.647480575426;11754.35351055683;11795.41022948849;11852.822207666892;653.761377097356;654.4012228810681;658.5127006015514;664.0636702249805;717.626834046293;5457.481539253765;5584.972075139598;5589.077802162597;5678.261825962599;6383.840403989938;110938.44530777294;111107;111111.44444277727;112936;118237;97722.51138744403;103214;103233;105479;105730;87688.80777980475;87917;88046;90008;90563;11562.47968841421;11952.521899123938;11990.400479975791;12034.759304813679;12092.972097371821;40990.180196396504;41241.793791030854;41336.1732765337;41504;42883.571164289206;54339.72830135824;54379.72810136082;54751;56241;57624.423755761905;18453.169607367432;18773.90613046926;18932.810671893654;18972.525686857385;19017.714734279274;19771.901140494207;19857;19933.302334418804;19965.700514492586;19973.101210445857;18924.43226703201;18941.810581894006;19183.904080480068;19251.4224573263;19548.902255488632;15133.924330378277;15297.694046118793;15373.23133843281;15399.07605543671;15451.304691289144;17353.652926941642;17537;17618.82381176172;17667.116644167996;17743.29026838912;13454.46182152703;13551.796723048965;13684.452621895012;13981.790273145707;14029.929850350683;14145.292735363397;14164;14208.147511149364;14326.355314010687;14372.137671739729 02:48:17 INFO - PID 3756 | __end_tp_report 02:48:17 INFO - PID 3756 | __start_cc_report 02:48:17 INFO - PID 3756 | _x_x_mozilla_cycle_collect,365 02:48:17 INFO - PID 3756 | __end_cc_report 02:48:17 INFO - PID 3756 | __startTimestamp1504259297091__endTimestamp 02:48:17 INFO - PID 3756 | 02:48:17 INFO - PID 3756 | ------- Summary: start ------- 02:48:17 INFO - PID 3756 | Number of tests: 6 02:48:17 INFO - PID 3756 | 02:48:17 INFO - PID 3756 | [#0] dojo.html Cycles:165 Average:6388.42 Median:6649.57 stddev:753.60 (11.3%) stddev-sans-first:716.25 02:48:17 INFO - PID 3756 | Values: 3313.2 3336.7 3338.1 3357.5 3368.5 6399.1 6413.1 6436.9 6454.0 6456.7 6354.4 6424.1 6448.5 6461.4 6473.7 5859.5 5895.8 5918.9 5941.6 6076.5 6010.2 6041.1 6042.0 6060.8 6073.2 5821.9 5989.7 6010.6 6098.0 6105.7 5866.2 5875.3 5950.6 5959.4 6011.6 5883.2 5921.9 5971.8 5978.5 6015.2 6349.4 6373.0 6377.9 6425.8 6548.0 6924.9 6990.7 6998.8 7004.8 7044.7 6977.5 7071.4 7077.0 7078.2 7086.1 6454.3 6461.5 6501.5 6561.6 6582.0 6973.2 7073.8 7079.0 7080.4 7086.9 6718.3 6760.0 6793.4 6799.3 6852.2 6975.7 6981.3 7000.2 7016.5 7042.8 6872.7 6943.8 6953.7 6972.0 6995.4 6900.9 6913.6 6924.5 7007.8 7021.4 5307.5 5377.3 5392.8 5460.0 5511.8 4700.8 4789.0 4849.3 4859.9 4885.7 6062.1 6145.8 6151.8 6171.9 6172.2 6949.7 6989.2 7019.4 7055.0 7076.5 5871.9 5925.9 5942.0 5959.9 6125.9 5918.1 5933.4 6018.0 6022.0 6026.7 6728.5 6775.2 6788.9 6802.9 6821.0 6966.1 6992.9 7063.1 7075.3 7083.8 6703.2 6728.8 6803.4 6819.1 6829.8 6649.6 6752.5 6754.7 6834.8 6928.5 6828.1 6923.6 6928.9 6944.2 6961.9 6679.4 6689.6 6694.2 6709.1 6765.9 6734.8 6776.1 6795.8 6834.5 6840.4 6360.1 6431.6 6665.1 6716.4 6759.0 6699.7 6713.9 6762.2 6884.4 6894.6 6061.1 6530.1 6609.5 6638.0 6641.7 02:48:17 INFO - PID 3756 | 02:48:17 INFO - PID 3756 | [#1] ext.html Cycles:180 Average:35966.51 Median:32212.19 stddev:51331.28 (159.4%) stddev-sans-first:51407.12 02:48:17 INFO - PID 3756 | Values: 737.4 819.3 840.3 841.6 1105.6 29181.6 40166.8 41203.0 41241.4 41306.8 40644.2 40948.2 41045.4 41165.0 41193.8 7508.8 7697.3 7698.3 7699.9 7699.9 7580.5 7639.2 7646.1 7672.2 7675.7 7667.5 7718.8 7726.1 7737.7 7741.9 7660.7 7692.8 7709.8 7712.0 7723.2 7539.3 7582.7 7588.6 7591.8 7592.2 35826.0 36026.6 36042.8 36077.6 36119.6 3543.8 3587.0 3588.4 3588.5 3590.3 30473.9 30485.2 30515.0 30572.2 30654.0 4886.4 5075.9 5076.4 5116.5 5121.1 185362.0 187611.0 187785.0 187817.0 188693.1 44638.3 44682.1 44697.6 44874.6 44881.0 48202.0 48237.3 48254.0 48331.3 48384.8 12597.1 12778.0 13984.7 14211.6 14236.7 11716.3 11723.4 11724.2 11726.0 11779.2 13372.9 13434.2 13474.5 13491.2 13606.9 5592.2 5641.2 5671.5 5679.4 5703.2 1006.7 1014.1 1014.9 1018.8 1027.2 22135.9 22184.9 22189.2 22555.8 22808.3 1116.9 1119.0 1130.6 1131.2 1131.8 11975.3 11996.6 12004.6 12049.9 12064.4 217968.0 219249.0 219997.0 220038.0 220209.0 49982.3 49987.8 50225.2 50505.0 50670.5 1412.9 1448.2 1448.8 1455.6 1459.1 22077.1 22131.4 22181.9 22353.9 22646.4 35570.0 35618.8 35662.5 35694.5 35739.3 185544.0 186096.0 186180.0 186369.0 186672.0 29521.0 29874.4 29958.7 32117.2 32307.2 41178.6 41465.8 41470.4 41532.2 41954.6 30833.4 30855.7 31189.8 31359.4 31518.7 30789.7 30846.7 30874.8 31147.7 31298.8 35033.5 35170.3 35249.5 35429.5 35483.1 30247.4 30504.4 30740.0 30773.4 30972.8 22851.4 22910.8 23140.7 24160.0 25435.6 02:48:17 INFO - PID 3756 | 02:48:17 INFO - PID 3756 | [#2] jquery.html Cycles:180 Average:35645.99 Median:23127.21 stddev:84796.57 (366.7%) stddev-sans-first:84993.52 02:48:17 INFO - PID 3756 | Values: 555.2 567.5 568.9 575.3 588.2 21714.3 22522.3 22564.3 22582.9 23404.6 25020.4 25827.2 25900.4 25904.5 25910.9 6506.8 6645.5 6656.7 6661.7 6666.0 6099.9 6489.6 6689.8 6694.3 6694.4 6651.0 6667.8 6670.6 6678.7 6687.6 6646.7 6655.3 6668.8 6671.0 6679.9 6196.8 6206.5 6214.3 6225.6 6227.1 27755.3 28204.3 28586.7 28918.9 29150.9 2044.4 2095.0 2095.7 2101.0 2102.6 19269.2 19909.2 19911.4 19943.6 19949.2 2411.3 2427.7 2447.8 2469.9 2519.7 135484.0 138803.0 138953.0 139833.0 140037.0 14188.3 14281.6 14317.9 14811.1 14933.6 22835.1 22837.7 22858.3 22898.7 22977.7 9398.0 9569.9 9573.2 9778.7 9817.6 7238.7 8825.2 8854.0 8862.9 8894.0 8032.2 8992.6 9064.2 9065.2 9345.2 2642.6 2717.4 2722.1 2800.8 2818.5 859.5 891.7 893.7 930.3 986.3 19241.8 19304.6 19450.9 19952.5 20128.6 1036.6 1044.9 1048.3 1048.3 1054.9 10633.5 10873.7 10890.8 10919.9 11048.0 479941.0 500190.0 508552.0 509965.0 511630.0 121087.4 122694.0 124541.4 125706.0 126619.4 1213.2 1217.8 1257.7 1269.8 1270.2 21228.0 21266.5 21337.1 21346.8 21871.3 56978.4 57804.7 58627.7 58812.4 60147.7 76565.0 77559.6 77892.6 78116.0 78597.6 11931.0 12425.6 13844.0 13922.8 14529.0 22761.9 23110.4 23144.0 23151.4 23177.9 17820.6 17934.9 18943.3 18974.3 18976.3 17248.7 17420.7 17534.6 17562.3 17821.6 18636.8 18962.8 19005.2 19037.6 19053.1 14482.7 14629.9 14765.3 14772.2 14780.9 18345.0 19700.6 19723.8 19726.7 19739.2 02:48:17 INFO - PID 3756 | 02:48:17 INFO - PID 3756 | [#3] mootools.html Cycles:180 Average:9491.74 Median:14850.89 stddev:6773.53 (45.6%) stddev-sans-first:6764.80 02:48:17 INFO - PID 3756 | Values: 1334.3 1373.8 1376.4 1380.0 1382.7 14483.9 15090.0 15116.8 15151.4 15179.8 15901.8 16149.4 16166.6 16199.8 16260.1 2702.6 2755.7 2756.5 2757.8 2758.9 2715.3 2844.8 2845.0 2846.6 2849.0 2790.3 2792.3 2823.0 2826.9 2829.3 2754.6 2771.5 2772.0 2782.2 2795.2 2681.2 2682.2 2694.2 2705.7 2708.7 14923.0 15204.5 15244.9 15316.3 15354.0 7860.1 8140.3 8175.0 8183.2 8191.7 5382.0 5543.1 5544.8 5617.1 5640.5 1928.4 1931.5 1934.2 1976.4 1990.8 26722.6 26940.0 26983.1 26989.9 26999.2 6794.6 6817.8 6846.1 6960.3 7062.6 17519.6 17585.6 17595.0 17610.6 17615.2 7867.4 8015.3 8027.0 8033.4 8041.8 7261.7 7290.9 7411.2 7417.4 7417.7 5210.9 5405.3 5438.5 5440.6 5455.6 1505.3 1531.2 1531.9 1537.8 1540.3 884.4 917.4 928.6 938.7 939.5 7793.9 7875.8 7905.2 7965.4 8027.7 714.9 990.8 1014.5 1026.8 1027.0 5354.5 5478.6 5519.2 5646.4 5807.4 21447.6 21647.2 21966.9 22200.1 22403.8 20879.6 21769.7 21918.5 21965.3 22251.6 11240.1 11641.1 11682.4 11690.9 11764.2 8714.1 8804.8 8806.3 8915.6 8946.5 17851.9 18630.8 19184.9 19335.0 19452.9 18594.2 18971.6 19325.2 19739.7 19811.2 11672.4 11955.3 11975.6 11990.9 12035.8 13980.4 14018.2 14128.0 14143.9 14148.0 11639.0 11681.5 11818.5 11923.4 12008.1 11300.7 11303.4 11440.8 11555.3 11657.2 9216.3 9315.8 9324.0 9339.0 9373.1 7272.9 7328.9 7356.2 7358.3 7369.4 7168.3 7184.1 7292.5 7294.8 7339.5 02:48:17 INFO - PID 3756 | 02:48:17 INFO - PID 3756 | [#4] prototype.html Cycles:180 Average:5639.06 Median:6312.48 stddev:1599.62 (25.3%) stddev-sans-first:1556.83 02:48:17 INFO - PID 3756 | Values: 495.8 497.6 531.2 533.9 536.5 6307.3 6397.1 6523.4 6526.3 6559.4 6249.5 6259.3 6264.3 6295.2 6368.5 5031.8 5042.4 5050.9 5055.3 5118.9 4915.0 5015.7 5032.7 5091.9 5104.0 4993.2 5022.5 5091.7 5103.2 5111.9 5001.6 5042.5 5068.5 5073.6 5088.3 4521.8 4565.5 4577.7 4583.4 4589.3 6302.8 6317.7 6323.9 6347.3 6363.2 5687.0 5782.5 5813.4 5848.3 5864.3 6618.3 6726.5 6731.5 6757.8 6771.9 4925.2 4932.2 4953.2 4979.6 5003.8 7057.2 7077.3 7078.2 7122.2 7139.8 5056.0 5069.9 5072.9 5083.2 5094.9 5137.4 5147.4 5159.4 5163.7 5208.9 5801.7 5815.0 5816.6 5843.8 5848.6 6644.7 6679.9 6690.5 6737.2 6802.9 1651.8 1686.2 1717.9 1736.2 1789.4 1203.3 1212.9 1229.0 1241.9 1248.1 5878.4 5886.4 5905.2 5908.7 5952.9 6717.8 6755.3 6806.5 6850.8 6906.5 5880.1 5903.4 5920.6 5947.7 5980.0 3629.4 3633.8 3650.0 3659.4 3718.8 6662.5 6705.9 6727.8 6745.4 6825.4 7118.5 7143.6 7144.7 7162.6 7205.5 6553.6 6688.8 6692.6 6694.4 6694.6 6797.3 6802.4 6859.8 6924.4 6930.3 6757.1 6811.6 6852.6 6855.9 6912.6 6596.4 6630.8 6665.2 6722.6 6752.6 5034.4 5064.8 5066.9 5079.3 5102.5 6720.7 6804.6 6957.0 6978.1 7024.4 6334.3 6523.9 6663.9 6692.5 6704.5 6579.9 6589.1 6604.5 6664.2 6727.4 6607.9 6614.3 6648.2 6666.3 6704.7 6644.9 6740.8 6746.7 6789.5 6822.7 6475.6 6496.6 6517.7 6557.0 6597.7 02:48:17 INFO - PID 3756 | 02:48:17 INFO - PID 3756 | [#5] yui.html Cycles:180 Average:23601.94 Median:19969.40 stddev:32327.92 (161.9%) stddev-sans-first:32373.67 02:48:17 INFO - PID 3756 | Values: 902.8 914.2 925.4 929.1 941.7 17880.9 18942.2 18974.1 18985.4 21357.6 19593.6 20193.0 20242.1 20279.0 20331.8 921.5 946.8 951.4 951.4 951.6 988.2 989.0 989.3 989.9 996.6 993.3 1032.3 1033.8 1035.5 1035.5 1012.6 1013.9 1014.0 1014.1 1015.7 751.8 753.1 753.5 753.8 754.0 18361.7 18745.2 18905.3 18922.3 20012.9 20765.8 20895.6 21017.6 21341.9 21508.1 16305.3 17063.1 17216.2 17287.1 17615.2 3756.9 3791.1 3791.3 3795.1 3795.6 122285.4 124578.4 126015.4 127267.4 127362.0 16844.8 17239.2 17521.3 17564.9 17687.1 30375.5 30755.4 30778.2 30791.4 31449.0 7485.7 7830.9 8731.4 9145.5 9206.1 3532.7 3608.5 3614.6 3615.8 3619.3 5303.5 5371.3 5383.0 5447.3 5469.7 1079.0 1087.0 1106.3 1109.6 1110.4 669.1 685.7 687.0 701.1 716.7 11540.5 11750.6 11754.4 11795.4 11852.8 653.8 654.4 658.5 664.1 717.6 5457.5 5585.0 5589.1 5678.3 6383.8 110938.4 111107.0 111111.4 112936.0 118237.0 97722.5 103214.0 103233.0 105479.0 105730.0 87688.8 87917.0 88046.0 90008.0 90563.0 11562.5 11952.5 11990.4 12034.8 12093.0 40990.2 41241.8 41336.2 41504.0 42883.6 54339.7 54379.7 54751.0 56241.0 57624.4 18453.2 18773.9 18932.8 18972.5 19017.7 19771.9 19857.0 19933.3 19965.7 19973.1 18924.4 18941.8 19183.9 19251.4 19548.9 15133.9 15297.7 15373.2 15399.1 15451.3 17353.7 17537.0 17618.8 17667.1 17743.3 13454.5 13551.8 13684.5 13981.8 14029.9 14145.3 14164.0 14208.1 14326.4 14372.1 02:48:17 INFO - PID 3756 | -------- Summary: end -------- 02:48:17 INFO - PID 3756 | 02:48:17 INFO - TEST-INFO | 3756: exit 0 02:48:17 INFO - TEST-OK | dromaeo_css | took 1114012ms 02:48:17 INFO - TEST-START | kraken 02:48:17 INFO - Initialising browser for kraken test... 02:48:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36348/getInfo.html -profile /tmp/tmpdfp6ap/profile 02:48:17 INFO - TEST-INFO | started process 4127 (/builds/slave/test/build/application/firefox/firefox http://localhost:36348/getInfo.html) 02:48:24 INFO - TEST-INFO | 4127: 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 - 'TERM': 'linux', 02:48:24 INFO - 'TMOUT': '86400', 02:48:24 INFO - 'USER': 'cltbld', 02:48:24 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121', 02:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:24 INFO - '_': '/tools/buildbot/bin/python'} 02:48:24 INFO - TEST-INFO | started process 4314 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdfp6ap/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 4314 | 02:48:25 INFO - PID 4314 | (/builds/slave/test/build/application/firefox/firefox:4365): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:48:25 INFO - PID 4314 | 02:48:35 INFO - PID 4314 | RSS: Main: 164761600 02:48:35 INFO - PID 4314 | 02:48:38 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36348/tests/kraken/driver.html?testName=audio-beat-detection) 02:48:39 INFO - PID 4314 | RSS: Main: 171089920 02:48:39 INFO - PID 4314 | 02:48:41 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36348/tests/kraken/driver.html?testName=audio-dft) 02:48:42 INFO - PID 4314 | RSS: Main: 174735360 02:48:42 INFO - PID 4314 | 02:48:45 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36348/tests/kraken/driver.html?testName=audio-fft) 02:48:45 INFO - PID 4314 | RSS: Main: 167034880 02:48:45 INFO - PID 4314 | 02:48:47 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36348/tests/kraken/driver.html?testName=audio-oscillator) 02:48:48 INFO - PID 4314 | RSS: Main: 168144896 02:48:48 INFO - PID 4314 | 02:48:50 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36348/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:51 INFO - PID 4314 | RSS: Main: 168173568 02:48:51 INFO - PID 4314 | 02:48:54 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36348/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:55 INFO - PID 4314 | RSS: Main: 169820160 02:48:55 INFO - PID 4314 | 02:48:58 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36348/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:59 INFO - PID 4314 | RSS: Main: 169320448 02:48:59 INFO - PID 4314 | 02:49:01 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36348/tests/kraken/driver.html?testName=json-parse-financial) 02:49:02 INFO - PID 4314 | RSS: Main: 169287680 02:49:02 INFO - PID 4314 | 02:49:04 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36348/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:04 INFO - PID 4314 | RSS: Main: 170020864 02:49:04 INFO - PID 4314 | 02:49:06 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36348/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:07 INFO - PID 4314 | RSS: Main: 169570304 02:49:07 INFO - PID 4314 | 02:49:09 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36348/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:49:09 INFO - PID 4314 | RSS: Main: 169476096 02:49:09 INFO - PID 4314 | 02:49:12 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36348/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:49:13 INFO - PID 4314 | RSS: Main: 169091072 02:49:13 INFO - PID 4314 | 02:49:16 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36348/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:16 INFO - PID 4314 | RSS: Main: 169807872 02:49:16 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | Cycle 1(1): loaded http://localhost:36348/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36348/tests/kraken/driver.html?testName=ai-astar) 02:49:18 INFO - PID 4314 | RSS: Main: 171184128 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | __start_tp_report 02:49:18 INFO - PID 4314 | _x_x_mozilla_page_load 02:49:18 INFO - PID 4314 | _x_x_mozilla_page_load_details 02:49:18 INFO - PID 4314 | |i|pagename|runs| 02:49:18 INFO - PID 4314 | |0;ai-astar;98;107;99;107;107;116;107;98;108;107 02:49:18 INFO - PID 4314 | |1;audio-beat-detection;141;113;147;174;120;140;142;116;163;115 02:49:18 INFO - PID 4314 | |2;audio-dft;163;164;164;162;162;163;162;165;172;163 02:49:18 INFO - PID 4314 | |3;audio-fft;74;74;73;147;74;73;73;90;96;75 02:49:18 INFO - PID 4314 | |4;audio-oscillator;79;78;125;80;80;79;124;80;80;125 02:49:18 INFO - PID 4314 | |5;imaging-gaussian-blur;114;115;114;114;114;114;115;114;119;113 02:49:18 INFO - PID 4314 | |6;imaging-darkroom;148;148;148;147;148;147;148;147;147;148 02:49:18 INFO - PID 4314 | |7;imaging-desaturate;94;94;94;94;94;93;94;94;94;93 02:49:18 INFO - PID 4314 | |8;json-parse-financial;53;54;54;54;59;62;54;53;54;55 02:49:18 INFO - PID 4314 | |9;json-stringify-tinderbox;39;38;38;38;37;39;39;38;38;39 02:49:18 INFO - PID 4314 | |10;stanford-crypto-aes;74;74;74;78;74;74;76;76;73;78 02:49:18 INFO - PID 4314 | |11;stanford-crypto-ccm;122;120;121;122;120;119;120;127;117;156 02:49:18 INFO - PID 4314 | |12;stanford-crypto-pbkdf2;173;176;172;176;178;170;169;180;167;172 02:49:18 INFO - PID 4314 | |13;stanford-crypto-sha256-iterative;65;64;65;65;65;64;62;65;64;65 02:49:18 INFO - PID 4314 | __end_tp_report 02:49:18 INFO - PID 4314 | __start_cc_report 02:49:18 INFO - PID 4314 | _x_x_mozilla_cycle_collect,938 02:49:18 INFO - PID 4314 | __end_cc_report 02:49:18 INFO - PID 4314 | __startTimestamp1504259358703__endTimestamp 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | ------- Summary: start ------- 02:49:18 INFO - PID 4314 | Number of tests: 14 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#0] ai-astar Cycles:10 Average:105.40 Median:112.00 stddev:5.60 (5.0%) stddev-sans-first:5.26 02:49:18 INFO - PID 4314 | Values: 98.0 107.0 99.0 107.0 107.0 116.0 107.0 98.0 108.0 107.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:141.50 stddev:21.04 (14.9%) stddev-sans-first:22.27 02:49:18 INFO - PID 4314 | Values: 141.0 113.0 147.0 174.0 120.0 140.0 142.0 116.0 163.0 115.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#2] audio-dft Cycles:10 Average:164.00 Median:163.50 stddev:2.98 (1.8%) stddev-sans-first:3.14 02:49:18 INFO - PID 4314 | Values: 163.0 164.0 164.0 162.0 162.0 163.0 162.0 165.0 172.0 163.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#3] audio-fft Cycles:10 Average:84.90 Median:74.00 stddev:23.30 (31.5%) stddev-sans-first:24.37 02:49:18 INFO - PID 4314 | Values: 74.0 74.0 73.0 147.0 74.0 73.0 73.0 90.0 96.0 75.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:79.50 stddev:21.86 (27.5%) stddev-sans-first:22.59 02:49:18 INFO - PID 4314 | Values: 79.0 78.0 125.0 80.0 80.0 79.0 124.0 80.0 80.0 125.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.00 stddev:1.65 (1.4%) stddev-sans-first:1.73 02:49:18 INFO - PID 4314 | Values: 114.0 115.0 114.0 114.0 114.0 114.0 115.0 114.0 119.0 113.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.52 (0.3%) stddev-sans-first:0.53 02:49:18 INFO - PID 4314 | Values: 148.0 148.0 148.0 147.0 148.0 147.0 148.0 147.0 147.0 148.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:49:18 INFO - PID 4314 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 93.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:54.00 stddev:2.94 (5.4%) stddev-sans-first:3.00 02:49:18 INFO - PID 4314 | Values: 53.0 54.0 54.0 54.0 59.0 62.0 54.0 53.0 54.0 55.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.50 stddev:0.67 (1.8%) stddev-sans-first:0.67 02:49:18 INFO - PID 4314 | Values: 39.0 38.0 38.0 38.0 37.0 39.0 39.0 38.0 38.0 39.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:75.00 stddev:1.79 (2.4%) stddev-sans-first:1.86 02:49:18 INFO - PID 4314 | Values: 74.0 74.0 74.0 78.0 74.0 74.0 76.0 76.0 73.0 78.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:121.50 stddev:11.40 (9.4%) stddev-sans-first:12.06 02:49:18 INFO - PID 4314 | Values: 122.0 120.0 121.0 122.0 120.0 119.0 120.0 127.0 117.0 156.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:174.50 stddev:4.14 (2.4%) stddev-sans-first:4.39 02:49:18 INFO - PID 4314 | Values: 173.0 176.0 172.0 176.0 178.0 170.0 169.0 180.0 167.0 172.0 02:49:18 INFO - PID 4314 | 02:49:18 INFO - PID 4314 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:0.97 (1.5%) stddev-sans-first:1.00 02:49:18 INFO - PID 4314 | Values: 65.0 64.0 65.0 65.0 65.0 64.0 62.0 65.0 64.0 65.0 02:49:18 INFO - PID 4314 | -------- Summary: end -------- 02:49:18 INFO - PID 4314 | 02:49:19 INFO - PID 4314 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 02:49:19 INFO - TEST-INFO | 4314: exit 0 02:49:19 INFO - TEST-OK | kraken | took 61627ms 02:49:19 INFO - SUITE-END | took 1175s 02:49:19 INFO - Completed test suite (00:19:36) 02:49:19 INFO - javascript benchmark 02:49:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8593.27216771347, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.155145437913, 3336.733061355091, 3338.1487720631235, 3357.479590663448, 3368.528406023157, 6399.104125422433, 6413.13422687937, 6436.903446448307, 6453.967730161348, 6456.7094480748365, 6354.396332348431, 6424.100625912364, 6448.451881590058, 6461.418472337488, 6473.708683109259, 5859.501942334907, 5895.823125306237, 5918.852028699295, 5941.613795103326, 6076.4834989025985, 6010.218671572689, 6041.063635136561, 6042.033274676052, 6060.757569697207, 6073.210482637251, 5821.912671309934, 5989.700514974255, 6010.639361638316, 6097.993831017877, 6105.6947152642415, 5866.178734977107, 5875.294964604275, 5950.613210141327, 5959.374265702082, 6011.639301641915, 5883.205767221413, 5921.940780592225, 5971.820845374646, 5978.461938425563, 6015.248093988252, 6349.36506349366, 6373.044043393482, 6377.936220637781, 6425.807225783234, 6548.0, 6924.896126558106, 6990.685419156151, 6998.75504357345, 7004.789856304319, 7044.718211271543, 6977.546459480168, 7071.36357727807, 7076.964615176943, 7078.221395646475, 7086.078809754699, 6454.257760357619, 6461.483081353481, 6501.512386570978, 6561.573497722633, 6582.012698095228, 6973.232944376115, 7073.787786366417, 7078.964605176941, 7080.362767350963, 7086.893696594555, 6718.260991290954, 6760.0, 6793.422559082534, 6799.252082270947, 6852.177738671292, 6975.720971161097, 6981.3367730065565, 7000.229974702778, 7016.473764467685, 7042.753503627348, 6872.69072891721, 6943.79168624942, 6953.721851126, 6971.954206868909, 6995.440364770806, 6900.896486552706, 6913.5506192097355, 6924.549904256207, 7007.754728584475, 7021.403180729593, 5307.548858347083, 5377.300950876439, 5392.811251606254, 5459.989901868167, 5511.751971161227, 4700.835470758507, 4789.042191561702, 4849.2726091086615, 4859.902801943941, 4885.7312847793, 6062.090686397076, 6145.754169833246, 6151.7539298428455, 6171.907421388682, 6172.166757487682, 6949.652517374212, 6989.1962424321855, 7019.403350715144, 7054.964725176341, 7076.504644674823, 5871.941280587225, 5925.911111333334, 5942.049272116527, 5959.880802384015, 6125.908111378333, 5918.053111502139, 5933.406659334032, 6017.969910150421, 6022.0, 6026.6986650667295, 6728.495362847806, 6775.186977562724, 6788.864222715518, 6802.931970680329, 6820.965895170492, 6966.094407727166, 6992.860142797217, 7063.081799366255, 7075.292470752884, 7083.7520686775715, 6703.229128650072, 6728.764493242908, 6803.42170915458, 6819.0794242778575, 6829.795106146824, 6649.567778094409, 6752.493562982698, 6754.662266886734, 6834.829129271808, 6928.4803639726215, 6828.112345395266, 6923.619200943795, 6928.861422771617, 6944.201416837129, 6961.930380696128, 6679.398854103154, 6689.565178263398, 6694.196696396463, 6709.127813384229, 6765.86468270622, 6734.831629209309, 6776.085228494288, 6795.762148324785, 6834.521583489108, 6840.41856442208, 6360.109584658059, 6431.5819471734185, 6665.133532640726, 6716.361945615159, 6758.986152077227, 6699.732010719516, 6713.865722685617, 6762.188537375547, 6884.4148247397525, 6894.551854129466, 6061.11998341753, 6530.118434011348, 6609.537332386687, 6638.0, 6641.701123449553], "value": 6332.804661225539, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.395335824624, 819.3445243804956, 840.2815592668269, 841.5792103948025, 1105.5964572930882, 29181.56227656584, 40166.799166004166, 41203.0, 41241.38137927934, 41306.7934660327, 40644.18711625773, 40948.181036379254, 41045.38431923516, 41165.0, 41193.7940310298, 7508.81227969301, 7697.26875946787, 7698.307152356287, 7699.923000769976, 7699.923000769976, 7580.507267027653, 7639.19788422216, 7646.082470103595, 7672.194419585947, 7675.731349402772, 7667.501612395205, 7718.7684369468725, 7726.111497177812, 7737.72917947872, 7741.8838717419285, 7660.6976813941765, 7692.846143077135, 7709.7687069388285, 7712.0, 7723.150453450116, 7539.283768042027, 7582.696692132308, 7588.620568971584, 7591.848163036709, 7592.202818704067, 35826.0, 36026.63973360259, 36042.81978590116, 36077.63922360769, 36119.63880361215, 3543.787372757643, 3586.9820650896577, 3588.3540962626716, 3588.4976103345552, 3590.2819436112622, 30473.93341233046, 30485.237869053228, 30515.0, 30572.235694107825, 30654.0, 4886.389201349832, 5075.923861142086, 5076.36545431821, 5116.539511443988, 5121.103806833789, 185362.0, 187611.0, 187784.99999999863, 187817.0, 188693.05653471782, 44638.33042504365, 44682.10635787266, 44697.55302446934, 44874.55125448769, 44881.0, 48202.0, 48237.27644085341, 48254.0, 48331.27503087457, 48384.75807620939, 12597.11820172579, 12778.0, 13984.720305594035, 14211.573652790434, 14236.715265694627, 11716.297022178696, 11723.35521546323, 11724.237924534878, 11726.0, 11779.234349767237, 13372.866271337161, 13434.193948363129, 13474.461021559224, 13491.190528568317, 13606.86393136056, 5592.161175823578, 5641.182028605829, 5671.489565939085, 5679.375268720438, 5703.201551782754, 1006.7332156978407, 1014.1278500489573, 1014.9492525373702, 1018.8369860822232, 1027.198784947742, 22135.889320553295, 22184.889075554518, 22189.223377181723, 22555.774442255697, 22808.315750527512, 1116.8827273136367, 1118.9664310070712, 1130.5760339872547, 1131.196850236325, 1131.7906187355366, 11975.28148311104, 11996.58011969577, 12004.639860804189, 12049.87950120505, 12064.396780161132, 217968.0, 219249.0, 219997.0, 220038.0, 220209.0, 49982.25026624603, 49987.75006124946, 50225.24662130071, 50505.0, 50670.49329506732, 1412.8869690424742, 1448.160067161044, 1448.8116544849313, 1455.6215384000025, 1459.1463993563673, 22077.116915323848, 22131.44671383164, 22181.889090554443, 22353.888230558743, 22646.433839153495, 35570.0, 35618.821905890305, 35662.465063024596, 35694.4645830318, 35739.28521429609, 185544.0, 186096.0, 186180.0, 186369.0, 186672.0, 29521.0, 29874.402511950073, 29958.700412996463, 32117.197070073435, 32307.19232019218, 41178.58821411747, 41465.792671037656, 41470.37794433146, 41532.169356612096, 41954.580454196286, 30833.383332333677, 30855.69144308528, 31189.8440507796, 31359.37281254408, 31518.684813152493, 30789.692103078683, 30846.69153308438, 30874.845625771726, 31147.688523115386, 31298.843505782326, 35033.474497883064, 35170.29659406746, 35249.47125793166, 35429.46855797217, 35483.11292217612, 30247.395052099277, 30504.389912201186, 30740.0, 30773.38453230878, 30972.845135774176, 22851.42871428161, 22910.770892290864, 23140.652890206322, 24160.0, 25435.6184657234], "value": 16587.24119149164, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.2310050579946, 567.492094575355, 568.9061304884694, 575.3268675649489, 588.2029849553854, 21714.34856954292, 22522.324330270076, 22564.323070307895, 22582.88708556459, 23404.648930266016, 25020.374490637743, 25827.225183244533, 25900.35249118773, 25904.48191036178, 25910.870445647794, 6506.772262970797, 6645.468362530986, 6656.733730650768, 6661.700223489955, 6665.966670166642, 6099.9085013724825, 6489.643069631168, 6689.7658581949645, 6694.330566943291, 6694.430973367245, 6651.0, 6667.833304167386, 6670.59976401415, 6678.699458524354, 6687.632180230085, 6646.667666616674, 6655.301193374698, 6668.833279168011, 6671.032700258435, 6679.866402671943, 6196.81409557714, 6206.534509911775, 6214.347493513161, 6225.595336303126, 6227.097070924736, 27755.306117347027, 28204.294892627848, 28586.714132858822, 28918.855405722836, 29150.85424572885, 2044.4071219346372, 2095.0467537270506, 2095.685647152939, 2101.0125241136643, 2102.6320393931155, 19269.22923083089, 19909.203631854707, 19911.402657920287, 19943.601127977505, 19949.202031918707, 2411.2525117213545, 2427.725444141822, 2447.8041756659422, 2469.9135530256353, 2519.748025197484, 135484.0, 138803.0, 138953.0, 139832.99999999898, 140037.0, 14188.290585470686, 14281.571552853431, 14317.923901034292, 14811.111333320036, 14933.626659333386, 22835.08659653595, 22837.657435138488, 22858.314250572508, 22898.65652015221, 22977.655335169984, 9398.0, 9569.904300957041, 9573.234141268682, 9778.65774697882, 9817.558209880572, 7238.674259658328, 8825.249853762502, 8853.955730221307, 8862.911370886337, 8893.955530222307, 8032.236937490929, 8992.582818528917, 9064.184223419923, 9065.229455496225, 9345.15893569582, 2642.5507663697217, 2717.4293398386167, 2722.060888993294, 2800.7791040303814, 2818.5208514552573, 859.5100792548312, 891.6968230801558, 893.7006102955453, 930.330162283155, 986.3391527676474, 19241.807581924, 19304.613907722047, 19450.902745486183, 19952.50118747043, 20128.59742805165, 1036.5853658536646, 1044.8850626431085, 1048.2924026282228, 1048.3133547526381, 1054.8945105489543, 10633.46832658364, 10873.673789786319, 10890.782184356269, 10919.890801092046, 11048.0, 479941.0, 500190.0, 508552.0, 509965.0, 511630.0, 121087.39456302662, 122694.0, 124541.37729311477, 125706.0, 126619.36690316674, 1213.1629175868622, 1217.8112392579164, 1257.704439456727, 1269.847618285793, 1270.2188154285234, 21228.0, 21266.468338291048, 21337.146514139262, 21346.786532135102, 21871.343859684235, 56978.435970380604, 57804.71097644485, 58627.70686146542, 58812.411875882404, 60147.69926150341, 76565.0, 77559.61220193862, 77892.61053694696, 78116.0, 78597.60701196457, 11931.0, 12425.62723118308, 13844.0, 13922.791158132433, 14529.0, 22761.88619056894, 23110.42223944415, 23144.0, 23151.421214469774, 23177.884110580013, 17820.643587128445, 17934.91032544829, 18943.33698320552, 18974.335898243495, 18976.335828245945, 17248.74126888123, 17420.651586968444, 17534.561635959202, 17562.29750809953, 17821.554461138574, 18636.813631863508, 18962.810371896103, 19005.239790408228, 19037.619247615246, 19053.14260858237, 14482.710345793235, 14629.92685036568, 14765.335559900044, 14772.18752968597, 14780.852191477947, 18345.0, 19700.605987879877, 19723.80276197277, 19726.704099438808, 19739.210431582575], "value": 12073.456757783426, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.2794890758992, 1373.8420081690601, 1376.4494202319077, 1379.9586012419625, 1382.6958069224768, 14483.855161448382, 15090.0, 15116.773248401269, 15151.409102044292, 15179.848201517996, 15901.840981590181, 16149.43476978306, 16166.595835104128, 16199.83800161998, 16260.105694186845, 2702.5946108083845, 2755.738204870534, 2756.476269508797, 2757.8483183424905, 2758.889644414231, 2715.2804506805714, 2844.7724182065385, 2845.046909285392, 2846.6014757933904, 2849.0455697341417, 2790.2745286225627, 2792.2879665685223, 2822.957655635167, 2826.886924523027, 2829.3351062500296, 2754.600582915466, 2771.459565384751, 2772.0436449424915, 2782.2348854065194, 2795.175423250146, 2681.2090433322023, 2682.1551211368355, 2694.191742477249, 2705.742954419327, 2708.6614173228345, 14923.0, 15204.46784362542, 15244.923775381052, 15316.310766015557, 15354.0, 7860.135385107634, 8140.308073813733, 8174.959125204396, 8183.222593853575, 8191.713290034821, 5382.0, 5543.057680194336, 5544.750486228089, 5617.101263797814, 5640.464155905182, 1928.3732786844332, 1931.4785008047888, 1934.1683076277195, 1976.3675623800389, 1990.7511561054869, 26722.5991610126, 26939.999999999804, 26983.05559305415, 26989.865050674816, 26999.190024299303, 6794.626295553693, 6817.829554261183, 6846.144231971004, 6960.338767817049, 7062.563415621602, 17519.64960700779, 17585.560360991076, 17595.0, 17610.64778704419, 17615.207315670825, 7867.449278550561, 8015.278624923785, 8027.0, 8033.357331413473, 8041.7989550260545, 7261.745838895613, 7290.927090729131, 7411.184769675331, 7417.369523590555, 7417.666205020787, 5210.947890521122, 5405.324334458192, 5438.45615438453, 5440.619156659075, 5455.645383050089, 1505.2925125191143, 1531.1808182622244, 1531.9004264722757, 1537.8385269546761, 1540.3145600208024, 884.4118661090303, 917.4449458077901, 928.6285485805597, 938.7043081429327, 939.5490164720835, 7793.883091753628, 7875.84248315042, 7905.249001344863, 7965.362770978308, 8027.678892844221, 714.8927660850914, 990.7912346936725, 1014.5130337438071, 1026.7741096958657, 1026.9794604107876, 5354.464553544614, 5478.643888147258, 5519.172124181405, 5646.350669672958, 5807.448292412214, 21447.571048578942, 21647.242346517796, 21966.89016554907, 22200.11199552032, 22403.775962240168, 20879.582408351747, 21769.673454898188, 21918.45203869884, 21965.34103976883, 22251.55496890053, 11240.100791936626, 11641.079221928456, 11682.357470339213, 11690.88309116915, 11764.235324703866, 8714.128587141235, 8804.779880503038, 8806.339524535557, 8915.598798053974, 8946.463212207289, 17851.910740446216, 18630.813691863452, 19184.90407547953, 19335.0, 19452.902735486234, 18594.163262653485, 18971.62056758885, 19325.226990920204, 19739.703904441732, 19811.20755169777, 11672.416379180837, 11955.342456164994, 11975.580854670045, 11990.880091198977, 12035.75928481408, 13980.44078236859, 14018.158910464997, 14128.0, 14143.858561414254, 14148.0, 11639.010684091947, 11681.474333655176, 11818.527258909548, 11923.403829808649, 12008.09939254577, 11300.660980170607, 11303.434828258718, 11440.771184576428, 11555.306681599131, 11657.183997120459, 9216.308776841897, 9315.813683726152, 9323.953380233055, 9338.95330523343, 9373.109554592304, 7272.854542909218, 7328.890066649111, 7356.154042285206, 7358.337749602561, 7369.37360324357, 7168.2831716827895, 7184.066071410683, 7292.489525733148, 7294.781156565312, 7339.522931009467], "value": 6791.254989780213, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.7665715949646, 497.55956505915367, 531.1819797511832, 533.9012282727696, 536.5090941788264, 6307.274663413708, 6397.104405383244, 6523.4455071318935, 6526.3031611780925, 6559.376859198374, 6249.5000399968, 6259.280182778982, 6264.342244064376, 6295.213098362705, 6368.535236895516, 5031.773570189332, 5042.445331013586, 5050.949490505094, 5055.317532133152, 5118.923216151742, 4915.041566894457, 5015.673981191211, 5032.672876263031, 5091.898162036757, 5103.953689493664, 4993.151164302078, 5022.522860328263, 5091.6690415123085, 5103.23451482277, 5111.923321150186, 5001.624878134155, 5042.470540593258, 5068.518490743373, 5073.568746656539, 5088.287639730441, 4521.841735539273, 4565.52062033485, 4577.665390650792, 4583.449986001668, 4589.334546490773, 6302.842428939267, 6317.684115794192, 6323.8735225295695, 6347.301796802348, 6363.204599425072, 5687.033204355228, 5782.479576838105, 5813.360530341659, 5848.2689663792025, 5864.266966629171, 6618.305077966792, 6726.529142959995, 6731.451766093777, 6757.763478278286, 6771.93455995926, 4925.162722337175, 4932.161532539477, 4953.182724850395, 4979.601631869478, 5003.849884503435, 7057.153141622935, 7077.327653872874, 7078.186008609025, 7122.216556178868, 7139.785806425815, 5055.974720126376, 5069.8732531687, 5072.873178170576, 5083.212102124176, 5094.949050509522, 5137.409197942285, 5147.356580427447, 5159.380874295109, 5163.690178589297, 5208.921866171935, 5801.680907550125, 5815.011448053841, 5816.592838501264, 5843.7501561898125, 5848.561357898175, 6644.700988455531, 6679.933200668028, 6690.464762818963, 6737.157855268091, 6802.945543440775, 1651.7687523746686, 1686.2243368050833, 1717.931282748676, 1736.175316724571, 1789.3647755047032, 1203.3441774233063, 1212.859911683015, 1228.993855030737, 1241.85097788266, 1248.095131030014, 5878.382769809194, 5886.440788125121, 5905.202797622352, 5908.734106965111, 5952.940470595238, 6717.798466046026, 6755.2906944771075, 6806.523543351917, 6850.780133337324, 6906.482013848981, 5880.147378630075, 5903.35063143054, 5920.5559583031445, 5947.691507868262, 5980.023284515748, 3629.4011488104693, 3633.800140992271, 3649.9635003649623, 3659.4144936810517, 3718.8326525306425, 6662.5003124765835, 6705.899411508832, 6727.831804204934, 6745.392914637706, 6825.35159159879, 7118.508522017252, 7143.571385716874, 7144.678489467987, 7162.570245785269, 7205.459590530626, 6553.574017688835, 6688.799336019927, 6692.59844409337, 6694.364035416627, 6694.564853284521, 6797.252302246848, 6802.387785099365, 6859.828504287433, 6924.4114250287275, 6930.341617546526, 6757.087793148059, 6811.591304521745, 6852.554583952027, 6855.862882742417, 6912.619805910723, 6596.406323430914, 6630.7679231226675, 6665.233498147775, 6722.596644201363, 6752.628605426552, 5034.421041580265, 5064.82273120439, 5066.949330506648, 5079.2888995539915, 5102.540771330598, 6720.731170753115, 6804.55770374924, 6957.0, 6978.057962175041, 7024.367806897404, 6334.271558770801, 6523.869522609426, 6663.900041499478, 6692.464602831859, 6704.4636429084585, 6579.868402631825, 6589.143411356493, 6604.471642268703, 6664.166979127609, 6727.394534491919, 6607.933920660732, 6614.338566143347, 6648.16897887764, 6666.266710661726, 6704.7318107277115, 6644.90032649501, 6740.820356437505, 6746.730130794714, 6789.524733268821, 6822.692978816064, 6475.579087359495, 6496.577722448026, 6517.73929042833, 6557.0, 6597.736090556324], "value": 5163.04170646356, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [902.823949329881, 914.2320450821309, 925.3753716241536, 929.1034152043277, 941.7410212191648, 17880.91059544702, 18942.24231030759, 18974.051297435126, 18985.43043708691, 21357.57284854302, 19593.608127837433, 20193.0, 20242.089105990228, 20279.0, 20331.796682033175, 921.548441263781, 946.8437707778243, 951.3578334624135, 951.3863558005082, 951.6431338248157, 988.214369576185, 989.0060489208336, 989.3272574649236, 989.9208063354914, 996.6013594562161, 993.3146129170885, 1032.3186696780126, 1033.8035773203105, 1035.4926086217736, 1035.5133087448887, 1012.6354512375539, 1013.908748212657, 1013.989860101397, 1014.1075853249167, 1015.6546774096805, 751.8007727952097, 753.073719325233, 753.506453273107, 753.8454616803542, 753.9811504712371, 18361.7245741314, 18745.156467958976, 18905.338313158973, 18922.3377181798, 20012.89993550023, 20765.792342076536, 20895.5820883583, 21017.5796484071, 21341.893290533604, 21508.139674413003, 16305.347786088661, 17063.14684265794, 17216.225269862887, 17287.13564321779, 17615.207315670825, 3756.906077348061, 3791.090138366772, 3791.2607041626893, 3795.1460921292764, 3795.582485926546, 122285.38857305745, 124578.37710811388, 126015.36992315072, 127267.36366318201, 127362.0, 16844.831551684572, 17239.22423490946, 17521.29914803419, 17564.91217543904, 17687.115644217738, 30375.544366834514, 30755.384892302027, 30778.230544236125, 30791.38417231643, 31449.0, 7485.737999170003, 7830.92169078302, 8731.432456890281, 9145.496997665192, 9206.125418085272, 3532.6643968822923, 3608.4587311903424, 3614.566252049771, 3615.7830530168276, 3619.2580520993133, 5303.522682958553, 5371.274877891435, 5383.0, 5447.319085114361, 5469.699166545799, 1079.0, 1086.9891301086889, 1106.3472551194834, 1109.6393672056452, 1110.4114819145866, 669.1134247122485, 685.715428097344, 686.989695154573, 701.0570782297904, 716.659586696315, 11540.538378464767, 11750.647480575426, 11754.35351055683, 11795.41022948849, 11852.822207666892, 653.761377097356, 654.4012228810681, 658.5127006015514, 664.0636702249805, 717.626834046293, 5457.481539253765, 5584.972075139598, 5589.077802162597, 5678.261825962599, 6383.840403989938, 110938.44530777294, 111107.0, 111111.44444277727, 112936.0, 118237.0, 97722.51138744403, 103214.0, 103233.0, 105479.0, 105730.0, 87688.80777980475, 87917.0, 88046.0, 90008.0, 90563.0, 11562.47968841421, 11952.521899123938, 11990.400479975791, 12034.759304813679, 12092.972097371821, 40990.180196396504, 41241.793791030854, 41336.1732765337, 41504.0, 42883.571164289206, 54339.72830135824, 54379.72810136082, 54751.0, 56241.0, 57624.423755761905, 18453.169607367432, 18773.90613046926, 18932.810671893654, 18972.525686857385, 19017.714734279274, 19771.901140494207, 19857.0, 19933.302334418804, 19965.700514492586, 19973.101210445857, 18924.43226703201, 18941.810581894006, 19183.904080480068, 19251.4224573263, 19548.902255488632, 15133.924330378277, 15297.694046118793, 15373.23133843281, 15399.07605543671, 15451.304691289144, 17353.652926941642, 17537.0, 17618.82381176172, 17667.116644167996, 17743.29026838912, 13454.46182152703, 13551.796723048965, 13684.452621895012, 13981.790273145707, 14029.929850350683, 14145.292735363397, 14164.0, 14208.147511149364, 14326.355314010687, 14372.137671739729], "value": 9054.622051352575, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1471.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 99.0, 107.0, 107.0, 116.0, 107.0, 98.0, 108.0, 107.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 113.0, 147.0, 174.0, 120.0, 140.0, 142.0, 116.0, 163.0, 115.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 164.0, 162.0, 162.0, 163.0, 162.0, 165.0, 172.0, 163.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 147.0, 74.0, 73.0, 73.0, 90.0, 96.0, 75.0], "value": 84.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 125.0, 80.0, 80.0, 79.0, 124.0, 80.0, 80.0, 125.0], "value": 93.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 115.0, 114.0, 119.0, 113.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 148.0, 147.0, 148.0, 147.0, 147.0, 148.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 54.0, 59.0, 62.0, 54.0, 53.0, 54.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 38.0, 38.0, 37.0, 39.0, 39.0, 38.0, 38.0, 39.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 78.0, 74.0, 74.0, 76.0, 76.0, 73.0, 78.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 120.0, 121.0, 122.0, 120.0, 119.0, 120.0, 127.0, 117.0, 156.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 176.0, 172.0, 176.0, 178.0, 170.0, 169.0, 180.0, 167.0, 172.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 65.0, 64.0, 62.0, 65.0, 64.0, 65.0], "value": 64.4, "unit": "score"}]}]} 02:49:19 INFO - Return code: 0 02:49:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:49:19 INFO - # TBPL SUCCESS # 02:49:19 INFO - Running post-action listener: _package_coverage_data 02:49:19 INFO - Running post-action listener: _resource_record_post_action 02:49:19 INFO - [mozharness: 2017-09-01 09:49:19.982503Z] Finished run-tests step (success) 02:49:19 INFO - Running post-run listener: _resource_record_post_run 02:49:20 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 5206016; Write bytes: 401137664; Read time: 1868; Write time: 250744 02:49:20 INFO - TinderboxPrint: CPU usage
13.1% 02:49:20 INFO - TinderboxPrint: I/O read bytes / time
5,206,016 / 1,868 02:49:20 INFO - TinderboxPrint: I/O write bytes / time
401,137,664 / 250,744 02:49:20 INFO - TinderboxPrint: CPU idle
8,264.4 (86.9%) 02:49:20 INFO - TinderboxPrint: CPU user
1,192.6 (12.5%) 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: 117682176; Read time: 0; Write time: 79024 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: 1177s; CPU: 13.0%; Read bytes: 3461120; Write bytes: 283455488; Read time: 1820; Write time: 171720 02:49:20 INFO - Running post-run listener: _upload_blobber_files 02:49:20 INFO - Blob upload gear active. 02:49:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:49:20 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:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:49:21 INFO - (blobuploader) - INFO - Open directory for files ... 02:49:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:49:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:49:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:24 INFO - (blobuploader) - INFO - Done attempting. 02:49:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:49:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:49:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:25 INFO - (blobuploader) - INFO - Done attempting. 02:49:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:49:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: 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/perfherder-data.json ... 02:49:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: 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 {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/872b3186786e9a1859811f910b162408768e81899923aed77bf328086e72a14229d5acc7e365db51e8a5745b44134532a998378b06ca1cfa5807201c5683e137", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da1e7072d1e13f2bf0511b00ab3068caab1f42af7b31fd35e782e8e7249e6ac4c2cc23289821313c34ff43271df10ad26efb601470bc281cedb767f4c08a6e3c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18e886c3c8fb0bdd1478dbb3556e72a6e9317a8575a8540a709406dd929851c02bcc0b6b2e1a3347a9fa757bbdae872c187761c0567b389ed4ad33c381c35a09", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4712043e97081cabf75740d23b723e82319d98d0f184d699422669ab65571d1163518e0aa4d69527fb002a732b378a2e7ec58e80639478d47035340732a39026"} 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:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/872b3186786e9a1859811f910b162408768e81899923aed77bf328086e72a14229d5acc7e365db51e8a5745b44134532a998378b06ca1cfa5807201c5683e137", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da1e7072d1e13f2bf0511b00ab3068caab1f42af7b31fd35e782e8e7249e6ac4c2cc23289821313c34ff43271df10ad26efb601470bc281cedb767f4c08a6e3c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18e886c3c8fb0bdd1478dbb3556e72a6e9317a8575a8540a709406dd929851c02bcc0b6b2e1a3347a9fa757bbdae872c187761c0567b389ed4ad33c381c35a09", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4712043e97081cabf75740d23b723e82319d98d0f184d699422669ab65571d1163518e0aa4d69527fb002a732b378a2e7ec58e80639478d47035340732a39026"} 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=1252.475808 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-01 02:49:28.083876) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:28.090921) ========= 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=199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/872b3186786e9a1859811f910b162408768e81899923aed77bf328086e72a14229d5acc7e365db51e8a5745b44134532a998378b06ca1cfa5807201c5683e137", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da1e7072d1e13f2bf0511b00ab3068caab1f42af7b31fd35e782e8e7249e6ac4c2cc23289821313c34ff43271df10ad26efb601470bc281cedb767f4c08a6e3c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18e886c3c8fb0bdd1478dbb3556e72a6e9317a8575a8540a709406dd929851c02bcc0b6b2e1a3347a9fa757bbdae872c187761c0567b389ed4ad33c381c35a09", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4712043e97081cabf75740d23b723e82319d98d0f184d699422669ab65571d1163518e0aa4d69527fb002a732b378a2e7ec58e80639478d47035340732a39026"} build_url:https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011224 build_url: 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/872b3186786e9a1859811f910b162408768e81899923aed77bf328086e72a14229d5acc7e365db51e8a5745b44134532a998378b06ca1cfa5807201c5683e137", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da1e7072d1e13f2bf0511b00ab3068caab1f42af7b31fd35e782e8e7249e6ac4c2cc23289821313c34ff43271df10ad26efb601470bc281cedb767f4c08a6e3c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18e886c3c8fb0bdd1478dbb3556e72a6e9317a8575a8540a709406dd929851c02bcc0b6b2e1a3347a9fa757bbdae872c187761c0567b389ed4ad33c381c35a09", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4712043e97081cabf75740d23b723e82319d98d0f184d699422669ab65571d1163518e0aa4d69527fb002a732b378a2e7ec58e80639478d47035340732a39026"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:28.118334) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:28.118715) ========= 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=199fb4c72b90437b990b419b000001c5-1504257806.579200-1387821121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004556 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:28.205332) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 02:49:28.208713) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 02:49:28.209049) ========= ========= Total master_lag: 0.22 =========