builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-078 starttime: 1504431783.03 results: success (0) revision: 59db725def8282e1d77e83f002d247c7d0f95237 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.025684) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.026123) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.026443) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.093570) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.093969) ========= 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=dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-03 02:43:03-- 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% 217M=0s 2017-09-03 02:43:03 (217 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.185016 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.341889) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.342208) ========= 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=dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.089209 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.489804) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 21 secs) (at 2017-09-03 02:43:03.490142) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 59db725def8282e1d77e83f002d247c7d0f95237 --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 59db725def8282e1d77e83f002d247c7d0f95237 --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=dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-03 02:43:03,642 truncating revision to first 12 chars 2017-09-03 02:43:03,642 Setting DEBUG logging. 2017-09-03 02:43:03,642 attempt 1/10 2017-09-03 02:43:03,642 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/59db725def82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-03 02:43:04,835 attempt 1/10 2017-09-03 02:43:05,681 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:05,682 sleeping for 9.00s (attempt 1/10) 2017-09-03 02:43:14,691 attempt 2/10 2017-09-03 02:43:15,206 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:15,206 sleeping for 13.50s (attempt 2/10) 2017-09-03 02:43:28,720 attempt 3/10 2017-09-03 02:43:29,235 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:29,235 sleeping for 21.25s (attempt 3/10) 2017-09-03 02:43:50,486 attempt 4/10 2017-09-03 02:43:51,177 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:51,178 sleeping for 32.88s (attempt 4/10) 2017-09-03 02:44:24,086 attempt 5/10 2017-09-03 02:44:24,392 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-03 02:44:24,688 unpacking tar archive at: mozilla-central-59db725def82/testing/mozharness/ program finished with exit code 0 elapsedTime=81.454929 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 21 secs) (at 2017-09-03 02:44:24.968127) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:24.968495) ========= script_repo_revision: 59db725def8282e1d77e83f002d247c7d0f95237 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:24.968889) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:24.969185) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:24.982917) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-09-03 02:44:24.983477) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:44:25 INFO - MultiFileLogger online at 20170903 02:44:25 in /builds/slave/test 02:44:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:44:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:44:25 INFO - {'append_to_log': False, 02:44:25 INFO - 'base_work_dir': '/builds/slave/test', 02:44:25 INFO - 'blob_upload_branch': 'Firefox', 02:44:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:44:25 INFO - 'branch': 'Firefox', 02:44:25 INFO - 'buildbot_json_path': 'buildprops.json', 02:44:25 INFO - 'code_coverage': False, 02:44:25 INFO - 'config_files': ('talos/linux_config.py',), 02:44:25 INFO - 'default_actions': ('clobber', 02:44:25 INFO - 'read-buildbot-config', 02:44:25 INFO - 'download-and-extract', 02:44:25 INFO - 'populate-webroot', 02:44:25 INFO - 'create-virtualenv', 02:44:25 INFO - 'install', 02:44:25 INFO - 'setup-mitmproxy', 02:44:25 INFO - 'run-tests'), 02:44:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:44:25 INFO - 'disable_ccov_upload': False, 02:44:25 INFO - 'disable_stylo': False, 02:44:25 INFO - 'download_minidump_stackwalk': True, 02:44:25 INFO - 'download_symbols': 'ondemand', 02:44:25 INFO - 'enable_stylo': False, 02:44:25 INFO - 'enable_webrender': False, 02:44:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:44:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:44:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:44:25 INFO - 'gecko_profile': False, 02:44:25 INFO - 'gecko_profile_interval': 0, 02:44:25 INFO - 'installer_path': 'installer.exe', 02:44:25 INFO - 'log_level': 'info', 02:44:25 INFO - 'log_name': 'talos', 02:44:25 INFO - 'log_to_console': True, 02:44:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:44:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:44:25 INFO - 'opt_config_files': (), 02:44:25 INFO - 'pip_index': False, 02:44:25 INFO - 'suite': 'dromaeojs-e10s', 02:44:25 INFO - 'system_bits': '32', 02:44:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:44:25 INFO - 'title': 'talos-linux64-ix-078', 02:44:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:44:25 INFO - 'use_talos_json': True, 02:44:25 INFO - 'verify': 'False', 02:44:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:44:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:44:25 INFO - 'work_dir': 'build'} 02:44:25 INFO - [mozharness: 2017-09-03 09:44:25.410228Z] Running clobber step. 02:44:25 INFO - Running pre-action listener: _resource_record_pre_action 02:44:25 INFO - Running main action method: clobber 02:44:25 INFO - rmtree: /builds/slave/test/build 02:44:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:44:27 INFO - Running post-action listener: _resource_record_post_action 02:44:27 INFO - [mozharness: 2017-09-03 09:44:27.532206Z] Finished clobber step (success) 02:44:27 INFO - [mozharness: 2017-09-03 09:44:27.532357Z] Running read-buildbot-config step. 02:44:27 INFO - Running pre-action listener: _resource_record_pre_action 02:44:27 INFO - Running main action method: read_buildbot_config 02:44:27 INFO - Using buildbot properties: 02:44:27 INFO - { 02:44:27 INFO - "project": "", 02:44:27 INFO - "product": "firefox", 02:44:27 INFO - "who": "archaeopteryx@coole-files.de", 02:44:27 INFO - "installer_path": "public/build/target.tar.bz2", 02:44:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 02:44:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:44:27 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 02:44:27 INFO - "stage_platform": "linux64-qr", 02:44:27 INFO - "basedir": "/builds/slave/test", 02:44:27 INFO - "buildnumber": 32, 02:44:27 INFO - "platform": "ubuntu64_hw_qr", 02:44:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:44:27 INFO - "slavebuilddir": "test", 02:44:27 INFO - "taskId": "bGpVjyYbR2W_GVsqwTknXA", 02:44:27 INFO - "branch": "mozilla-central", 02:44:27 INFO - "script_repo_revision": "production", 02:44:27 INFO - "revision": "59db725def8282e1d77e83f002d247c7d0f95237", 02:44:27 INFO - "slavename": "talos-linux64-ix-078", 02:44:27 INFO - "repo_path": "mozilla-central" 02:44:27 INFO - } 02:44:27 INFO - Finding installer, test and symbols from parent task. 02:44:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bGpVjyYbR2W_GVsqwTknXA'}, attempt #1 02:44:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bGpVjyYbR2W_GVsqwTknXA'}, attempt #1 02:44:28 INFO - Task dependencies: JG8GD8vBTLSpKotpMpyhnw 02:44:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw'}, attempt #1 02:44:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json 02:44:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip 02:44:28 INFO - Running post-action listener: _resource_record_post_action 02:44:28 INFO - [mozharness: 2017-09-03 09:44:28.884184Z] Finished read-buildbot-config step (success) 02:44:28 INFO - [mozharness: 2017-09-03 09:44:28.884401Z] Running download-and-extract step. 02:44:28 INFO - Running pre-action listener: _resource_record_pre_action 02:44:28 INFO - Running main action method: download_and_extract 02:44:28 INFO - mkdir: /builds/slave/test/build/tests 02:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:28 INFO - trying https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json 02:44:28 INFO - Downloading https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:44:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:44:29 INFO - Downloaded 989 bytes. 02:44:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:44:29 INFO - Using the following test package requirements: 02:44:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:44:29 INFO - u'common': [u'target.common.tests.zip'], 02:44:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:44:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:44:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:44:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:44:29 INFO - u'mozbase': [u'target.common.tests.zip'], 02:44:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:44:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:44:29 INFO - u'web-platform': [u'target.common.tests.zip', 02:44:29 INFO - u'target.web-platform.tests.tar.gz'], 02:44:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:44:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:44:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip 02:44:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:44:29 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip into memory 02:44:30 INFO - Content-Length response header: 55022487 02:44:30 INFO - Bytes received: 55022487 02:44:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:44:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip 02:44:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:44:42 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip into memory 02:44:43 INFO - Content-Length response header: 55022487 02:44:43 INFO - Bytes received: 55022487 02:44:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.talos.tests.zip 02:44:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:44:56 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.talos.tests.zip into memory 02:44:57 INFO - Content-Length response header: 14496650 02:44:57 INFO - Bytes received: 14496650 02:44:57 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:44:57 INFO - trying https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:57 INFO - Downloading https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:44:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:44:58 INFO - Downloaded 62661590 bytes. 02:44:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:58 INFO - mkdir: /builds/slave/test/properties 02:44:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:44:58 INFO - Writing to file /builds/slave/test/properties/build_url 02:44:58 INFO - Contents: 02:44:58 INFO - build_url:https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:58 INFO - Running post-action listener: _resource_record_post_action 02:44:58 INFO - Running post-action listener: find_tests_for_verification 02:44:58 INFO - Running post-action listener: set_extra_try_arguments 02:44:58 INFO - [mozharness: 2017-09-03 09:44:58.794897Z] Finished download-and-extract step (success) 02:44:58 INFO - [mozharness: 2017-09-03 09:44:58.795125Z] Running populate-webroot step. 02:44:58 INFO - Running pre-action listener: _resource_record_pre_action 02:44:58 INFO - Running main action method: populate_webroot 02:44:58 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:44:58 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:44:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:44:58 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:44:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:44:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:58 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:58 INFO - 'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:44:58 INFO - 'tests': ['damp', 'tps']}, 02:44:58 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:58 INFO - 'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['damp', 'tps']}, 02:44:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:44:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:44:58 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:44:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:44:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:58 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:58 INFO - 'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:58 INFO - 'other-e10s': {'tests': ['a11yr', 02:44:58 INFO - 'ts_paint', 02:44:58 INFO - 'tpaint', 02:44:58 INFO - 'sessionrestore', 02:44:58 INFO - 'sessionrestore_many_windows', 02:44:58 INFO - 'sessionrestore_no_auto_restore', 02:44:58 INFO - 'tabpaint']}, 02:44:58 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['a11yr', 02:44:58 INFO - 'ts_paint', 02:44:58 INFO - 'tpaint', 02:44:58 INFO - 'sessionrestore', 02:44:58 INFO - 'sessionrestore_many_windows', 02:44:58 INFO - 'sessionrestore_no_auto_restore', 02:44:58 INFO - 'tabpaint']}, 02:44:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:44:58 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:44:58 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['bloom_basic_singleton']}, 02:44:58 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['bloom_basic']}, 02:44:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:44:58 INFO - 'tsvgr_opacity', 02:44:58 INFO - 'tart', 02:44:58 INFO - 'tscrollx', 02:44:58 INFO - 'tsvg_static']}, 02:44:58 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['tsvgx', 02:44:58 INFO - 'tsvgr_opacity', 02:44:58 INFO - 'tart', 02:44:58 INFO - 'tscrollx', 02:44:58 INFO - 'tsvg_static']}, 02:44:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:44:58 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:58 INFO - 'talos_options': ['--enable-stylo'], 02:44:58 INFO - 'tests': ['tp5o']}, 02:44:58 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:58 INFO - 'talos_options': ['--mitmproxy', 02:44:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:58 INFO - '--firstNonBlankPaint'], 02:44:58 INFO - 'tests': ['tp6_google', 02:44:58 INFO - 'tp6_youtube', 02:44:58 INFO - 'tp6_amazon', 02:44:58 INFO - 'tp6_facebook']}, 02:44:58 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:58 INFO - 'talos_options': ['--enable-stylo', 02:44:58 INFO - '--mitmproxy', 02:44:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:58 INFO - '--firstNonBlankPaint'], 02:44:58 INFO - 'tests': ['tp6_google', 02:44:58 INFO - 'tp6_youtube', 02:44:58 INFO - 'tp6_amazon', 02:44:58 INFO - 'tp6_facebook']}, 02:44:58 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:58 INFO - 'talos_options': ['--enable-stylo', 02:44:58 INFO - '--stylo-threads=1', 02:44:58 INFO - '--mitmproxy', 02:44:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:58 INFO - '--firstNonBlankPaint'], 02:44:58 INFO - 'tests': ['tp6_google', 02:44:58 INFO - 'tp6_youtube', 02:44:58 INFO - 'tp6_amazon', 02:44:58 INFO - 'tp6_facebook']}, 02:44:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:44:58 INFO - 'talos_options': ['--xperf_path', 02:44:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:58 INFO - 'tests': ['tp5n']}, 02:44:58 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:58 INFO - 'talos_options': ['--enable-stylo', 02:44:58 INFO - '--xperf_path', 02:44:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:58 INFO - 'tests': ['tp5n']}}} 02:44:58 INFO - Running post-action listener: _resource_record_post_action 02:44:58 INFO - [mozharness: 2017-09-03 09:44:58.810962Z] Finished populate-webroot step (success) 02:44:58 INFO - [mozharness: 2017-09-03 09:44:58.811051Z] Running create-virtualenv step. 02:44:58 INFO - Running pre-action listener: _resource_record_pre_action 02:44:58 INFO - Running main action method: create_virtualenv 02:44:58 INFO - Creating virtualenv /builds/slave/test/build/venv 02:44:58 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:44:58 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:44:58 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:44:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:44:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:59 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:59 INFO - Installing pip...............done. 02:44:59 INFO - Return code: 0 02:44:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:45:00 INFO - Reading from file tmpfile_stdout 02:45:00 INFO - Output received: 02:45:00 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:45:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:45:00 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:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:00 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:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:00 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 0x1edc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2248a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, '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': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', '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:45:00 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:45:00 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:45:00 INFO - Using env: {'DISPLAY': ':0', 02:45:00 INFO - 'HOME': '/home/cltbld', 02:45:00 INFO - 'LANG': 'en_US.UTF-8', 02:45:00 INFO - 'LANGUAGE': 'en_US:en', 02:45:00 INFO - 'LOGNAME': 'cltbld', 02:45:00 INFO - 'MAIL': '/var/mail/cltbld', 02:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:00 INFO - 'MOZ_NO_REMOTE': '1', 02:45:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:00 INFO - 'NO_EM_RESTART': '1', 02:45:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:00 INFO - 'PWD': '/builds/slave/test', 02:45:00 INFO - 'SHELL': '/bin/bash', 02:45:00 INFO - 'SHLVL': '1', 02:45:00 INFO - 'TERM': 'linux', 02:45:00 INFO - 'TMOUT': '86400', 02:45:00 INFO - 'USER': 'cltbld', 02:45:00 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:00 INFO - '_': '/tools/buildbot/bin/python'} 02:45:01 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:45:01 INFO - Downloading/unpacking pip>=1.5 02:45:01 INFO - Running setup.py egg_info for package pip 02:45:01 INFO - warning: no files found matching 'pip/cacert.pem' 02:45:01 INFO - warning: no files found matching '*.html' under directory 'docs' 02:45:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:45:01 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:45:01 INFO - Installing collected packages: pip 02:45:01 INFO - Found existing installation: pip 1.1 02:45:01 INFO - Uninstalling pip: 02:45:01 INFO - Successfully uninstalled pip 02:45:01 INFO - Running setup.py install for pip 02:45:01 INFO - warning: no files found matching 'pip/cacert.pem' 02:45:01 INFO - warning: no files found matching '*.html' under directory 'docs' 02:45:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:45:01 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:45:01 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:45:01 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:45:01 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:45:01 INFO - Successfully installed pip 02:45:01 INFO - Cleaning up... 02:45:01 INFO - Return code: 0 02:45:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:01 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 0x1edc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2248a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, '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': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', '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:45:01 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:45:01 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:45:01 INFO - Using env: {'DISPLAY': ':0', 02:45:01 INFO - 'HOME': '/home/cltbld', 02:45:01 INFO - 'LANG': 'en_US.UTF-8', 02:45:01 INFO - 'LANGUAGE': 'en_US:en', 02:45:01 INFO - 'LOGNAME': 'cltbld', 02:45:01 INFO - 'MAIL': '/var/mail/cltbld', 02:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:01 INFO - 'MOZ_NO_REMOTE': '1', 02:45:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:01 INFO - 'NO_EM_RESTART': '1', 02:45:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:01 INFO - 'PWD': '/builds/slave/test', 02:45:01 INFO - 'SHELL': '/bin/bash', 02:45:01 INFO - 'SHLVL': '1', 02:45:01 INFO - 'TERM': 'linux', 02:45:01 INFO - 'TMOUT': '86400', 02:45:01 INFO - 'USER': 'cltbld', 02:45:01 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:01 INFO - '_': '/tools/buildbot/bin/python'} 02:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:01 INFO - Downloading/unpacking psutil>=3.1.1 02:45:01 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:45:01 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:45:01 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:45:01 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:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:45:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:45:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:45:02 INFO - Installing collected packages: psutil 02:45:02 INFO - Running setup.py install for psutil 02:45:02 INFO - building 'psutil._psutil_linux' extension 02:45:02 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:45:03 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:45:03 INFO - building 'psutil._psutil_posix' extension 02:45:03 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:45:04 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:45:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:45:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:45:04 INFO - Successfully installed psutil 02:45:04 INFO - Cleaning up... 02:45:04 INFO - Return code: 0 02:45:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2248a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, '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': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', '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:45:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:45:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:45:04 INFO - Using env: {'DISPLAY': ':0', 02:45:04 INFO - 'HOME': '/home/cltbld', 02:45:04 INFO - 'LANG': 'en_US.UTF-8', 02:45:04 INFO - 'LANGUAGE': 'en_US:en', 02:45:04 INFO - 'LOGNAME': 'cltbld', 02:45:04 INFO - 'MAIL': '/var/mail/cltbld', 02:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:04 INFO - 'MOZ_NO_REMOTE': '1', 02:45:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:04 INFO - 'NO_EM_RESTART': '1', 02:45:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:04 INFO - 'PWD': '/builds/slave/test', 02:45:04 INFO - 'SHELL': '/bin/bash', 02:45:04 INFO - 'SHLVL': '1', 02:45:04 INFO - 'TERM': 'linux', 02:45:04 INFO - 'TMOUT': '86400', 02:45:04 INFO - 'USER': 'cltbld', 02:45:04 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:04 INFO - '_': '/tools/buildbot/bin/python'} 02:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:45:05 INFO - Installing collected packages: mozsystemmonitor 02:45:05 INFO - Running setup.py install for mozsystemmonitor 02:45:05 INFO - Successfully installed mozsystemmonitor 02:45:05 INFO - Cleaning up... 02:45:05 INFO - Return code: 0 02:45:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:45: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:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45: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:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:05 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 0x1edc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2248a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, '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': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', '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:45:05 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:45:05 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:45:05 INFO - Using env: {'DISPLAY': ':0', 02:45:05 INFO - 'HOME': '/home/cltbld', 02:45:05 INFO - 'LANG': 'en_US.UTF-8', 02:45:05 INFO - 'LANGUAGE': 'en_US:en', 02:45:05 INFO - 'LOGNAME': 'cltbld', 02:45:05 INFO - 'MAIL': '/var/mail/cltbld', 02:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:05 INFO - 'MOZ_NO_REMOTE': '1', 02:45:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:05 INFO - 'NO_EM_RESTART': '1', 02:45:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:05 INFO - 'PWD': '/builds/slave/test', 02:45:05 INFO - 'SHELL': '/bin/bash', 02:45:05 INFO - 'SHLVL': '1', 02:45:05 INFO - 'TERM': 'linux', 02:45:05 INFO - 'TMOUT': '86400', 02:45:05 INFO - 'USER': 'cltbld', 02:45:05 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:05 INFO - '_': '/tools/buildbot/bin/python'} 02:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:05 INFO - Downloading/unpacking jsonschema==2.5.1 02:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:45:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:45:07 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:45:07 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:45:07 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:45:07 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:45:07 INFO - Downloading functools32-3.2.3-2.tar.gz 02:45:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:45:07 INFO - warning: no files found matching '*.txt' 02:45:07 INFO - no previously-included directories found matching 'build' 02:45:07 INFO - no previously-included directories found matching 'dist' 02:45:07 INFO - no previously-included directories found matching '.git*' 02:45:07 INFO - Installing collected packages: jsonschema, functools32 02:45:07 INFO - Running setup.py install for functools32 02:45:07 INFO - warning: no files found matching '*.txt' 02:45:07 INFO - no previously-included directories found matching 'build' 02:45:07 INFO - no previously-included directories found matching 'dist' 02:45:07 INFO - no previously-included directories found matching '.git*' 02:45:07 INFO - Successfully installed jsonschema functools32 02:45:07 INFO - Cleaning up... 02:45:07 INFO - Return code: 0 02:45:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:45: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:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45: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:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45: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', '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 0x1edc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2248a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, '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': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', '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:45: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:45: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 functools32==3.2.3-2 02:45:07 INFO - Using env: {'DISPLAY': ':0', 02:45:07 INFO - 'HOME': '/home/cltbld', 02:45:07 INFO - 'LANG': 'en_US.UTF-8', 02:45:07 INFO - 'LANGUAGE': 'en_US:en', 02:45:07 INFO - 'LOGNAME': 'cltbld', 02:45:07 INFO - 'MAIL': '/var/mail/cltbld', 02:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:07 INFO - 'MOZ_NO_REMOTE': '1', 02:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:07 INFO - 'NO_EM_RESTART': '1', 02:45:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:07 INFO - 'PWD': '/builds/slave/test', 02:45:07 INFO - 'SHELL': '/bin/bash', 02:45:07 INFO - 'SHLVL': '1', 02:45:07 INFO - 'TERM': 'linux', 02:45:07 INFO - 'TMOUT': '86400', 02:45:07 INFO - 'USER': 'cltbld', 02:45:07 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:07 INFO - '_': '/tools/buildbot/bin/python'} 02:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:45:07 INFO - Cleaning up... 02:45:07 INFO - Return code: 0 02:45:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:45: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:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45: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:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45: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', '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 0x1edc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2248a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, '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': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', '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:45: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:45: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 blobuploader==1.2.4 02:45:07 INFO - Using env: {'DISPLAY': ':0', 02:45:07 INFO - 'HOME': '/home/cltbld', 02:45:07 INFO - 'LANG': 'en_US.UTF-8', 02:45:07 INFO - 'LANGUAGE': 'en_US:en', 02:45:07 INFO - 'LOGNAME': 'cltbld', 02:45:07 INFO - 'MAIL': '/var/mail/cltbld', 02:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:07 INFO - 'MOZ_NO_REMOTE': '1', 02:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:07 INFO - 'NO_EM_RESTART': '1', 02:45:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:07 INFO - 'PWD': '/builds/slave/test', 02:45:07 INFO - 'SHELL': '/bin/bash', 02:45:07 INFO - 'SHLVL': '1', 02:45:07 INFO - 'TERM': 'linux', 02:45:07 INFO - 'TMOUT': '86400', 02:45:07 INFO - 'USER': 'cltbld', 02:45:07 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:07 INFO - '_': '/tools/buildbot/bin/python'} 02:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:07 INFO - Downloading/unpacking blobuploader==1.2.4 02:45:07 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:45:07 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:45:07 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:45:07 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:45:08 INFO - Downloading blobuploader-1.2.4.tar.gz 02:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:45:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:45:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:45: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:45: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:45: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:45: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:45:09 INFO - Downloading docopt-0.6.1.tar.gz 02:45:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:45:09 INFO - Installing collected packages: blobuploader, requests, docopt 02:45:09 INFO - Running setup.py install for blobuploader 02:45:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:45:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:45:09 INFO - Running setup.py install for requests 02:45:09 INFO - Running setup.py install for docopt 02:45:10 INFO - Successfully installed blobuploader requests docopt 02:45:10 INFO - Cleaning up... 02:45:10 INFO - Return code: 0 02:45:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:10 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 0x1edc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2248a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, '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': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', '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:45:10 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:45:10 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:45:10 INFO - Using env: {'DISPLAY': ':0', 02:45:10 INFO - 'HOME': '/home/cltbld', 02:45:10 INFO - 'LANG': 'en_US.UTF-8', 02:45:10 INFO - 'LANGUAGE': 'en_US:en', 02:45:10 INFO - 'LOGNAME': 'cltbld', 02:45:10 INFO - 'MAIL': '/var/mail/cltbld', 02:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:10 INFO - 'MOZ_NO_REMOTE': '1', 02:45:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:10 INFO - 'NO_EM_RESTART': '1', 02:45:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:10 INFO - 'PWD': '/builds/slave/test', 02:45:10 INFO - 'SHELL': '/bin/bash', 02:45:10 INFO - 'SHLVL': '1', 02:45:10 INFO - 'TERM': 'linux', 02:45:10 INFO - 'TMOUT': '86400', 02:45:10 INFO - 'USER': 'cltbld', 02:45:10 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:10 INFO - '_': '/tools/buildbot/bin/python'} 02:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:45:10 INFO - Running setup.py (path:/tmp/pip-FTmC5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:45:10 INFO - Running setup.py (path:/tmp/pip-0HdIGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:45:10 INFO - Running setup.py (path:/tmp/pip-cguKZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:45:10 INFO - Running setup.py (path:/tmp/pip-IbtgsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:45:10 INFO - Running setup.py (path:/tmp/pip-cGivZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:45:10 INFO - Running setup.py (path:/tmp/pip-UcQ3nw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:45:10 INFO - Running setup.py (path:/tmp/pip-qbggNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:45:10 INFO - Running setup.py (path:/tmp/pip-iTNVnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:45:10 INFO - Running setup.py (path:/tmp/pip-BRctui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:45:10 INFO - Running setup.py (path:/tmp/pip-Qany07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:45:10 INFO - Running setup.py (path:/tmp/pip-5e7b1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:45:11 INFO - Running setup.py (path:/tmp/pip-3iZg1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:45:11 INFO - Running setup.py (path:/tmp/pip-mvG2ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:45:11 INFO - Running setup.py (path:/tmp/pip-9OINPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:11 INFO - Running setup.py (path:/tmp/pip-fIP12B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:45:11 INFO - Running setup.py (path:/tmp/pip-eWk_9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:45:11 INFO - Running setup.py (path:/tmp/pip-SbmbBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:45:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:45:11 INFO - Running setup.py install for manifestparser 02:45:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:45:11 INFO - Running setup.py install for mozcrash 02:45:11 INFO - Running setup.py install for mozdebug 02:45:11 INFO - Running setup.py install for mozdevice 02:45:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:45:11 INFO - Running setup.py install for mozfile 02:45:11 INFO - Running setup.py install for mozhttpd 02:45:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Running setup.py install for mozinfo 02:45:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Running setup.py install for mozInstall 02:45:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Running setup.py install for mozleak 02:45:12 INFO - Running setup.py install for mozlog 02:45:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Running setup.py install for moznetwork 02:45:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Running setup.py install for mozprocess 02:45:12 INFO - Running setup.py install for mozprofile 02:45:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Running setup.py install for mozrunner 02:45:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:45:12 INFO - Running setup.py install for mozscreenshot 02:45:13 INFO - Running setup.py install for moztest 02:45:13 INFO - Running setup.py install for mozversion 02:45:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:45:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:45:13 INFO - Cleaning up... 02:45:13 INFO - Return code: 0 02:45:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45: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:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45: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:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:13 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 0x1edc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2248a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, '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': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', '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:45:13 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:45:13 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:45:13 INFO - Using env: {'DISPLAY': ':0', 02:45:13 INFO - 'HOME': '/home/cltbld', 02:45:13 INFO - 'LANG': 'en_US.UTF-8', 02:45:13 INFO - 'LANGUAGE': 'en_US:en', 02:45:13 INFO - 'LOGNAME': 'cltbld', 02:45:13 INFO - 'MAIL': '/var/mail/cltbld', 02:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:13 INFO - 'MOZ_NO_REMOTE': '1', 02:45:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:13 INFO - 'NO_EM_RESTART': '1', 02:45:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:13 INFO - 'PWD': '/builds/slave/test', 02:45:13 INFO - 'SHELL': '/bin/bash', 02:45:13 INFO - 'SHLVL': '1', 02:45:13 INFO - 'TERM': 'linux', 02:45:13 INFO - 'TMOUT': '86400', 02:45:13 INFO - 'USER': 'cltbld', 02:45:13 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:13 INFO - '_': '/tools/buildbot/bin/python'} 02:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:45:13 INFO - Running setup.py (path:/tmp/pip-cInSzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:45:13 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:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:45:13 INFO - Running setup.py (path:/tmp/pip-CDJQqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:45:13 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:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:45:13 INFO - Running setup.py (path:/tmp/pip-3wf6da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:45:13 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:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:45:13 INFO - Running setup.py (path:/tmp/pip-BXAb9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:45:13 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:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:45:13 INFO - Running setup.py (path:/tmp/pip-7XpFTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:45:13 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:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:45:13 INFO - Running setup.py (path:/tmp/pip-wedLjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:45:13 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:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:45:13 INFO - Running setup.py (path:/tmp/pip-23U0FI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:45:13 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:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:45:13 INFO - Running setup.py (path:/tmp/pip-056_Dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:45:14 INFO - Running setup.py (path:/tmp/pip-3ZJxMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:45:14 INFO - Running setup.py (path:/tmp/pip-DOhYAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:45:14 INFO - Running setup.py (path:/tmp/pip-86zW0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:45:14 INFO - Running setup.py (path:/tmp/pip-LXC4NA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:45:14 INFO - Running setup.py (path:/tmp/pip-CPIIDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:45:14 INFO - Running setup.py (path:/tmp/pip-2izZ1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:14 INFO - Running setup.py (path:/tmp/pip-gGXewv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:45:14 INFO - Running setup.py (path:/tmp/pip-fWPnxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:45:14 INFO - Running setup.py (path:/tmp/pip-ogLzE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:45:14 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:45:14 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:45:14 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:45:14 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:45:14 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:45:14 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:45:14 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:45:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:45: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:45: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:45: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:45: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:45:15 INFO - Downloading blessings-1.6.tar.gz 02:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:45:15 INFO - Installing collected packages: blessings 02:45:15 INFO - Running setup.py install for blessings 02:45:15 INFO - Successfully installed blessings 02:45:15 INFO - Cleaning up... 02:45:16 INFO - Return code: 0 02:45:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:45:16 INFO - Reading from file tmpfile_stdout 02:45:16 INFO - Current package versions: 02:45:16 INFO - argparse == 1.2.1 02:45:16 INFO - blessings == 1.6 02:45:16 INFO - blobuploader == 1.2.4 02:45:16 INFO - docopt == 0.6.1 02:45:16 INFO - functools32 == 3.2.3-2 02:45:16 INFO - jsonschema == 2.5.1 02:45:16 INFO - manifestparser == 1.1 02:45:16 INFO - mozInstall == 1.13 02:45:16 INFO - mozcrash == 1.0 02:45:16 INFO - mozdebug == 0.1 02:45:16 INFO - mozdevice == 0.51 02:45:16 INFO - mozfile == 1.2 02:45:16 INFO - mozhttpd == 0.7 02:45:16 INFO - mozinfo == 0.10 02:45:16 INFO - mozleak == 0.1 02:45:16 INFO - mozlog == 3.5 02:45:16 INFO - moznetwork == 0.27 02:45:16 INFO - mozprocess == 0.25 02:45:16 INFO - mozprofile == 0.28 02:45:16 INFO - mozrunner == 6.13 02:45:16 INFO - mozscreenshot == 0.1 02:45:16 INFO - mozsystemmonitor == 0.3 02:45:16 INFO - moztest == 0.8 02:45:16 INFO - mozversion == 1.4 02:45:16 INFO - psutil == 3.1.1 02:45:16 INFO - requests == 1.2.3 02:45:16 INFO - wsgiref == 0.1.2 02:45:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:16 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 0x1edc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2248a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, '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': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', '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:45:16 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:45:16 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:45:16 INFO - Using env: {'DISPLAY': ':0', 02:45:16 INFO - 'HOME': '/home/cltbld', 02:45:16 INFO - 'LANG': 'en_US.UTF-8', 02:45:16 INFO - 'LANGUAGE': 'en_US:en', 02:45:16 INFO - 'LOGNAME': 'cltbld', 02:45:16 INFO - 'MAIL': '/var/mail/cltbld', 02:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:16 INFO - 'MOZ_NO_REMOTE': '1', 02:45:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:16 INFO - 'NO_EM_RESTART': '1', 02:45:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:16 INFO - 'PWD': '/builds/slave/test', 02:45:16 INFO - 'SHELL': '/bin/bash', 02:45:16 INFO - 'SHLVL': '1', 02:45:16 INFO - 'TERM': 'linux', 02:45:16 INFO - 'TMOUT': '86400', 02:45:16 INFO - 'USER': 'cltbld', 02:45:16 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:16 INFO - '_': '/tools/buildbot/bin/python'} 02:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:45: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:45: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:45: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:45: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:45:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:45:17 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:45:17 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:45:17 INFO - Installing collected packages: simplejson 02:45:17 INFO - Running setup.py install for simplejson 02:45:17 INFO - building 'simplejson._speedups' extension 02:45:17 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:45:18 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:45:18 INFO - Successfully installed simplejson 02:45:18 INFO - Cleaning up... 02:45:18 INFO - Return code: 0 02:45:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:18 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 0x1edc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2248a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, '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': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', '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:45:18 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:45:18 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:45:18 INFO - Using env: {'DISPLAY': ':0', 02:45:18 INFO - 'HOME': '/home/cltbld', 02:45:18 INFO - 'LANG': 'en_US.UTF-8', 02:45:18 INFO - 'LANGUAGE': 'en_US:en', 02:45:18 INFO - 'LOGNAME': 'cltbld', 02:45:18 INFO - 'MAIL': '/var/mail/cltbld', 02:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:18 INFO - 'MOZ_NO_REMOTE': '1', 02:45:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:18 INFO - 'NO_EM_RESTART': '1', 02:45:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:18 INFO - 'PWD': '/builds/slave/test', 02:45:18 INFO - 'SHELL': '/bin/bash', 02:45:18 INFO - 'SHLVL': '1', 02:45:18 INFO - 'TERM': 'linux', 02:45:18 INFO - 'TMOUT': '86400', 02:45:18 INFO - 'USER': 'cltbld', 02:45:18 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:18 INFO - '_': '/tools/buildbot/bin/python'} 02:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:45:18 INFO - Cleaning up... 02:45:18 INFO - Return code: 0 02:45:18 INFO - Running post-action listener: _resource_record_post_action 02:45:18 INFO - Running post-action listener: _start_resource_monitoring 02:45:18 INFO - Starting resource monitoring. 02:45:18 INFO - [mozharness: 2017-09-03 09:45:18.986730Z] Finished create-virtualenv step (success) 02:45:18 INFO - [mozharness: 2017-09-03 09:45:18.987676Z] Running install step. 02:45:18 INFO - Running pre-action listener: _resource_record_pre_action 02:45:18 INFO - Running main action method: install 02:45:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:45:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:45:19 INFO - Reading from file tmpfile_stdout 02:45:19 INFO - Detecting whether we're running mozinstall >=1.0... 02:45:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:45:19 INFO - Reading from file tmpfile_stdout 02:45:19 INFO - Output received: 02:45:19 INFO - Usage: mozinstall [options] installer 02:45:19 INFO - Options: 02:45:19 INFO - -h, --help show this help message and exit 02:45:19 INFO - -d DEST, --destination=DEST 02:45:19 INFO - Directory to install application into. [default: 02:45:19 INFO - "/builds/slave/test"] 02:45:19 INFO - --app=APP Application being installed. [default: firefox] 02:45:19 INFO - mkdir: /builds/slave/test/build/application 02:45:19 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:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:45:35 INFO - Reading from file tmpfile_stdout 02:45:35 INFO - Output received: 02:45:35 INFO - /builds/slave/test/build/application/firefox/firefox 02:45:35 INFO - Running post-action listener: _resource_record_post_action 02:45:35 INFO - [mozharness: 2017-09-03 09:45:35.824523Z] Finished install step (success) 02:45:35 INFO - [mozharness: 2017-09-03 09:45:35.824777Z] Running setup-mitmproxy step. 02:45:35 INFO - Running pre-action listener: _resource_record_pre_action 02:45:35 INFO - Running main action method: setup_mitmproxy 02:45:35 INFO - Skipping: mitmproxy is not required 02:45:35 INFO - Running post-action listener: _resource_record_post_action 02:45:35 INFO - [mozharness: 2017-09-03 09:45:35.825954Z] Finished setup-mitmproxy step (success) 02:45:35 INFO - [mozharness: 2017-09-03 09:45:35.826174Z] Running run-tests step. 02:45:35 INFO - Running pre-action listener: _resource_record_pre_action 02:45:35 INFO - Running pre-action listener: _set_gcov_prefix 02:45:35 INFO - Running main action method: run_tests 02:45:35 WARNING - Try message not found. 02:45:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:35 INFO - Python 2.7.3 02:45:35 INFO - Return code: 0 02:45:35 INFO - grabbing minidump binary from tooltool 02:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:35 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 0x224c090>, '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 0x224cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2246f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:35 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:45:35 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:45:35 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:45:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:36 INFO - Return code: 0 02:45:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:45:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:36 INFO - ENV: RUST_BACKTRACE is now full 02:45:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:45:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--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:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-078 --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:45:36 INFO - Using env: {'DISPLAY': ':0', 02:45:36 INFO - 'HOME': '/home/cltbld', 02:45:36 INFO - 'LANG': 'en_US.UTF-8', 02:45:36 INFO - 'LANGUAGE': 'en_US:en', 02:45:36 INFO - 'LOGNAME': 'cltbld', 02:45:36 INFO - 'MAIL': '/var/mail/cltbld', 02:45:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:36 INFO - 'MOZ_ACCELERATED': '1', 02:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:36 INFO - 'MOZ_NO_REMOTE': '1', 02:45:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:36 INFO - 'MOZ_WEBRENDER': '1', 02:45:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:36 INFO - 'NO_EM_RESTART': '1', 02:45:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:36 INFO - 'PWD': '/builds/slave/test', 02:45:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:36 INFO - 'RUST_BACKTRACE': 'full', 02:45:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:36 INFO - 'SHELL': '/bin/bash', 02:45:36 INFO - 'SHLVL': '1', 02:45:36 INFO - 'TERM': 'linux', 02:45:36 INFO - 'TMOUT': '86400', 02:45:36 INFO - 'USER': 'cltbld', 02:45:36 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:36 INFO - '_': '/tools/buildbot/bin/python'} 02:45:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--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:45:36 INFO - mozversion application_buildid: 20170903085316 02:45:36 INFO - mozversion application_changeset: 59db725def8282e1d77e83f002d247c7d0f95237 02:45:36 INFO - mozversion application_display_name: Nightly 02:45:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:36 INFO - mozversion application_name: Firefox 02:45:36 INFO - mozversion application_remotingname: firefox 02:45:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:45:36 INFO - mozversion application_vendor: Mozilla 02:45:36 INFO - mozversion application_version: 57.0a1 02:45:36 INFO - mozversion platform_buildid: 20170903085316 02:45:36 INFO - mozversion platform_changeset: 59db725def8282e1d77e83f002d247c7d0f95237 02:45:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:45:36 INFO - mozversion platform_version: 57.0a1 02:45:36 INFO - using testdate: 1504431936 02:45:36 INFO - actual date: 1504431936 02:45:36 INFO - starting webserver on 'localhost:46539' 02:45:36 INFO - SUITE-START | Running 2 tests 02:45:36 INFO - TEST-START | dromaeo_css 02:45:36 INFO - Initialising browser for dromaeo_css test... 02:45:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46539/getInfo.html -profile /tmp/tmpb8W2Gf/profile 02:45:36 INFO - TEST-INFO | started process 3650 (/builds/slave/test/build/application/firefox/firefox http://localhost:46539/getInfo.html) 02:45:46 INFO - TEST-INFO | 3650: exit 0 02:45:46 INFO - Browser initialized. 02:45:46 INFO - Running cycle 1/1 for dromaeo_css test... 02:45:46 INFO - Using env: {'DISPLAY': ':0', 02:45:46 INFO - 'HOME': '/home/cltbld', 02:45:46 INFO - 'JSGC_DISABLE_POISONING': '1', 02:45:46 INFO - 'LANG': 'en_US.UTF-8', 02:45:46 INFO - 'LANGUAGE': 'en_US:en', 02:45:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:45:46 INFO - 'LOGNAME': 'cltbld', 02:45:46 INFO - 'MAIL': '/var/mail/cltbld', 02:45:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:46 INFO - 'MOZ_ACCELERATED': '1', 02:45:46 INFO - 'MOZ_CRASHREPORTER': '1', 02:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:45:46 INFO - 'MOZ_NO_REMOTE': '1', 02:45:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:46 INFO - 'MOZ_WEBRENDER': '1', 02:45:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:46 INFO - 'NO_EM_RESTART': '1', 02:45:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:46 INFO - 'PWD': '/builds/slave/test', 02:45:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:46 INFO - 'RUST_BACKTRACE': 'full', 02:45:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:46 INFO - 'SHELL': '/bin/bash', 02:45:46 INFO - 'SHLVL': '1', 02:45:46 INFO - 'TERM': 'linux', 02:45:46 INFO - 'TMOUT': '86400', 02:45:46 INFO - 'USER': 'cltbld', 02:45:46 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 02:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:46 INFO - '_': '/tools/buildbot/bin/python'} 02:45:46 INFO - TEST-INFO | started process 3849 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb8W2Gf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:47 INFO - PID 3849 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:45:47 INFO - PID 3849 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:45:47 INFO - PID 3849 | 02:45:47 INFO - PID 3849 | (/builds/slave/test/build/application/firefox/firefox:3911): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:45:47 INFO - PID 3849 | 02:45:58 INFO - PID 3849 | RSS: Main: 202739712 02:45:58 INFO - PID 3849 | 02:46:01 INFO - PID 3849 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:48:58 INFO - PID 3849 | Cycle 1(1): loaded http://localhost:46539/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46539/tests/dromaeo/cssquery-ext.html) 02:48:58 INFO - PID 3849 | RSS: Main: 213528576 02:48:58 INFO - PID 3849 | 02:52:00 INFO - PID 3849 | Cycle 1(1): loaded http://localhost:46539/tests/dromaeo/cssquery-ext.html (next: http://localhost:46539/tests/dromaeo/cssquery-jquery.html) 02:52:01 INFO - PID 3849 | RSS: Main: 211931136 02:52:01 INFO - PID 3849 | 02:55:03 INFO - PID 3849 | Cycle 1(1): loaded http://localhost:46539/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46539/tests/dromaeo/cssquery-mootools.html) 02:55:04 INFO - PID 3849 | RSS: Main: 214401024 02:55:04 INFO - PID 3849 | 02:58:06 INFO - PID 3849 | Cycle 1(1): loaded http://localhost:46539/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46539/tests/dromaeo/cssquery-prototype.html) 02:58:06 INFO - PID 3849 | RSS: Main: 212979712 02:58:06 INFO - PID 3849 | 03:01:10 INFO - PID 3849 | Cycle 1(1): loaded http://localhost:46539/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46539/tests/dromaeo/cssquery-yui.html) 03:01:11 INFO - PID 3849 | RSS: Main: 213573632 03:01:11 INFO - PID 3849 | 03:04:13 INFO - PID 3849 | Cycle 1(1): loaded http://localhost:46539/tests/dromaeo/cssquery-yui.html (next: http://localhost:46539/tests/dromaeo/cssquery-dojo.html) 03:04:13 INFO - PID 3849 | RSS: Main: 216670208 03:04:13 INFO - PID 3849 | 03:04:13 INFO - PID 3849 | __start_tp_report 03:04:13 INFO - PID 3849 | _x_x_mozilla_page_load 03:04:13 INFO - PID 3849 | _x_x_mozilla_page_load_details 03:04:13 INFO - PID 3849 | |i|pagename|runs| 03:04:13 INFO - PID 3849 | |0;dojo.html;3285.080177550287;3302.356040572088;3332.350191712617;3376;3380.847861846218;6307.243130824308;6380.202474690664;6401.263854656716;6414.422701956829;6419.197600299963;6368.681565921708;6384.712687929043;6420.390062944025;6426.228852537702;6451.709673064712;5882.588218824684;5893.145493903381;5919.940800592003;5933.258342707161;5937.4656280934705;5858.853528661775;5940.465358117768;5942.732577034044;5968.522518198533;5968.940310596882;5911.852203694921;6024.96987515064;6025.8192254232445;6055.27336719592;6060.575759696823;5889.998700220973;5962.612430192024;6018.578699491038;6037;6042.96978515109;5753.741081651336;5775.047117225653;5808.1578171165365;5849.736761845727;5878.970605146989;6386.0740192672265;6441.581297215666;6448.742050317982;6477.22273327199;6478.190226221723;6903.413209877166;6923.065386172852;6926.82244018518;6955.408790252784;6994.0558024666525;6784.6946887389695;6963.268856770017;6967.6167810770385;7073.434125269966;7077.327653872874;6366.681665916685;6375.6174629522375;6412.3267056959285;6420.422162005395;6467.676616169267;7135.322144396274;7140.7857764267155;7150.606716630531;7151.356377926011;7168.53404528704;6640.535162538576;6822.147231596051;6828.146481689789;6853.348931851466;6862.656867156722;6853.588784672936;6882.655867206719;6948.722051117999;6961.23426423093;6967.407770339477;6807.591544507246;6818.147731533559;6897.9655101725175;6905.136857892763;6942.548734332252;6697.3637504437;6786.321367863175;6807.4894382921475;6812.591244525344;6815.42068924147;5355.625106242525;5367.221752845893;5370.087085195526;5385.569154467633;5400.62195646301;4688.460827004938;4694.460137084211;4797.160496913027;4801.807927682923;4836.903261934812;5959.4934430573885;6061.787837425757;6099.878002439927;6104.084387341934;6109;6865.347791959756;6928.064711264015;6970.407515361149;6985;6985.511014228955;5806.129080637938;5957.791477298274;6019.548533859978;6138.14066030756;6139.539534534928;5883.970580147072;5892.941070589325;5927.940720592739;5971.104334349882;5971.164037034819;6686.431653309523;6690.431313338323;6758.05387245786;6763.898541521881;6775.3902148805855;6908.654567271717;6937.583744975318;7005.124359455068;7007.019017337679;7015.543989640657;6589.604623722592;6628.90056649141;6738.2924792898;6757.729690812313;6766.120404347404;6561.376669216417;6598.901016484852;6772.051912732123;6807.455403567802;6829.112215411965;6852.246252912076;6970.198427180939;6988.510804243654;6999.440044796303;7005.369516743313;6702.396784289437;6710.228323742832;6735.090762747162;6742.8651426972165;6753.459723222229;6642.4021838034805;6673.699683514156;6712.731490740316;6762.087118238975;6766.18805743314;6396.968015159894;6553.115329430657;6608.240052394036;6699.430548403246;6710;6682.899756503559;6686.83282917931;6741.1236539249585;6798.184217893884;6815.897761533482;6298.937010629835;6400.103985442135;6461.256955450183;6469.5794773339585;6524.412802847766 03:04:13 INFO - PID 3849 | |1;ext.html;790.7509134622593;792.6393490961613;821.7000794709929;839.391441205126;858.7423772868138;41836.58163418365;42482.78758606206;42671.14657706852;42735.57264427355;42828.57171428285;38757.806210968905;38883;39197.80401097998;39255.60744392555;39363;6818.147731533571;6903.689333979958;6944.6180460074675;6949.791506254821;6954.617496037716;6784.457243420539;6864.931350686479;6877.243503214668;6938.965305173492;6961.269066747995;6900.551464154839;6945.374916257536;6953.95690646402;7685.423593230502;7867.291943725064;7718.961405192994;7746.806329841715;7808.102068262166;7835.373170146375;7848.489848159851;7681.692732290701;7745.6127193640095;7753.108392534874;7759.224077592252;7768.65041073153;37829;38555.42166867499;38573.80713096417;38669;38680.41979370312;3472.44440889459;3566.661167189113;3569.28614277143;3571.357155711971;3579.4451859961487;32206.194845128826;32568.6743132568;32579.185520361945;32732.181695457566;32769.344613107845;5208.427073021964;5219.739013049332;5221.4256431792855;5269.710165940872;5282.97358513205;186838;188359;190189;190376;202051;49661;49718;49739.25391119136;49899.501004989484;50085;51231.74384128056;51237.74381128071;51353.74323128435;51581.742091290056;51726.741366292925;15885.444009459614;15970.041797491955;15974.281157347947;15987.280572374271;15988.280527376295;12892.033097517537;13050.54323098687;13058.804117938238;13068.869311306955;13083.803742943863;14487.20320382389;14559.19924404168;14591.124532528082;14618.342174602169;14684.26578671062;5912.398544640345;5926.170336152942;6014.969925150346;6021.488173505301;6035.1249068885645;990.1089019882164;1014.7463134216446;1016.8169729448771;1023.918086553074;1029.8712660917386;28376.290592734935;29321.413571728444;29368.706312937025;29522.11433656994;29597.408051838844;1101.6474728087096;1108.0692218536467;1111.2665640677114;1112.5994641928899;1135.7501349703052;12446.751064978649;12575.748485030432;12762.936185319015;12786.296753678636;15851.603709907344;239963.80018100148;240187;241410;241659;242474;57336.42663573311;57941.71029144912;58045;58400.415995839496;58433.41566584365;1391.1444461656008;1460.9700161386197;1463.0855715178013;1473.035161968912;1474.86726194643;27511.08031169456;27573.448531028866;27877;27877;28350.29124271827;39227.607723922396;39272.214555709295;39578;39699;39827.203455931296;202865;204012;205163;205520;206487;31817.681823181472;32787.67212327846;34537.48193777146;34635;35532;40909.38635920404;43664.781676091414;43669.56330436782;43823.56176438322;43993.78003109964;31909.68090319067;31921.521177181898;32465.51301730523;32473.188170295933;33234.66765332316;31438.37123257568;31483;31510.842445787624;31643.683563164075;31869.20326991844;36268.63731362758;36556;36698.26603467969;36818;36855.07862303464;30484.390312193187;30843.845780770953;30915;31183.84408078036;31194.532082018333;22896.313110606043;23245.76754232436;23347.299581012598;23481.530369392174;23654.408639784142 03:04:13 INFO - PID 3849 | |2;jquery.html;554.9694766787825;565.8626161415553;567.6594043573856;571.9227904232929;591.5208680968415;21832.781672183275;22477.438064048387;22529.21147759829;22548.88725556374;22560.21039263626;23332.41668958277;25383.365415864613;25498.74501254987;25737.87131064342;25788.742112578868;6569.671516424183;6651.700673469705;6734.494912881553;6734.730610775563;6740.292269311725;6426.485881129522;6580.210374755012;6598.274189839114;6626.5030122740745;6772.051912732221;6676.666166691671;6711.4295284900845;6733.730650773963;6738.460923126163;6743.258241593421;6598.175228096488;6696.430803381694;6711.127553418073;6714.899276510881;6720.059191713164;6173.32093469718;6224.62652240867;6224.782132625382;6229.09678096678;6239.906401403982;28172.295692607644;28221.012264570847;28315.85842070797;28492.857535712395;28927.42145157085;1991.8804871707744;2026.5946810637784;2041.2141325589607;2043.3665563675308;2045.3863840847835;19127.521811954673;19767;19769;19790.10944507486;19867.50331241716;2233.798958093779;2262.2534563593977;2265.501589650291;2268.727752669674;2371.312319427364;135328;136488;138140.99999999898;138925;139013.3049334747;13734.939759036224;14448.277586120863;14577.198254096127;14624.85375146235;14659.48691795782;22842.31473055811;23162.768372316397;23164.073437062667;23213;23468.765312347;9918.900810991941;10288.176945844314;10364.740881477872;10465.529051192714;10513.316634418738;9414.95292523533;9417.952910235404;9609;9661.903380966241;9685.965947151037;7521.984532088098;8919.554022298858;9064.637414503479;9135.269178465707;9210.81578368429;2627.2906315294763;2669.452762183748;2671.639328690602;2673.8262012969094;2705.971730742305;961.1974001708512;983.7786498038028;986.8322385194534;1005.9195264378832;1007.8488226765897;19049.904750476435;19357.032148392525;19393.51516212106;19505.512362191057;19788.90105549463;1050.669039252633;1059.21618002679;1069.2889228662852;1072.9463526823627;1073.7315671082229;10594.523246453928;10663.84004239937;10686.679399618024;10756.784864302826;10973.561057557768;538329;539383;544368;544733;545073;121037;124913;125108.37445812895;126236.36881815532;127654;1208.2871106047298;1214.5627574073326;1252.7557126360273;1263.5451237554473;1263.9873601264053;20479;20645.380638580868;20733.68899466479;21107.47231319168;21170.576588467837;56145.43854561402;56158.71920640371;57027.714861425426;57523.42476575348;58792.70603647125;72485.6375718118;72947.63526182548;72986.27013729795;73283;74019.62990185014;12125.636230913087;12579.119461637598;14204.431822727389;14487.565373038826;20496.38510844677;23761.881190594628;24398.634020489353;24488;24700.50598988046;24784;18944.242230311185;19147.425577232705;19200.71198932045;19474;19553.217871285557;16952.406665766637;17058.402955896483;17090;17286.394976175772;17803.821961780217;18324.358647447276;19605;19682.50793730111;19683.70474442856;19685.50786230356;19827.702584461535;20342.59314813725;20359.083841227475;20406;20452.488687782923;18765.155567999747;18808.435746927615;19084.236630534622;19095.23619055278;19255.22979080821 03:04:13 INFO - PID 3849 | |3;mootools.html;1314.9013823963203;1363.3592211660527;1367.9110857794242;1371.053972758797;1379.4206433298014;14739.778903316446;15190.468333608298;15241.933064685463;15355.769663455043;15398.461053863117;15039.548813535612;16026.198690065508;16091.195440228;16102.516924492284;16119.83880161198;2772.085211880085;2813.831170129799;2818.3658676797763;2836.347640042781;2837.3615936414244;2722.101706436881;2808.2979255186206;2816.2396153038667;2817.577363395497;2818.7463128318445;2604.622329762183;2827.8444685542286;2827.8586070696488;2829.39168078863;2831.688514263429;2780.1659502149273;2784.7354501322343;2797.5523916173315;2816.690164081949;2870.5120129577917;2776.3475583237923;2777.986110069457;2803.8317700937805;2806.4667713134645;2808.36811717364;14720.116792992454;15124.168170750605;15145.621359465991;15196.164210968393;15225.695486090326;8127;8335.916640833575;8357.582120893992;8360.414770966036;8405.915940840636;5246.134387826004;5479.1781232815;5528.944710552883;5600.047991841397;5697.743601537941;1936.5352315444334;1961.901904904749;1964.1259639460445;1986.1558837494008;2007.65869802134;26356;26616.73383266162;26663.866680666666;26670.733292667213;26677.06630267951;6798.966005170041;6835.213950395669;6840.11078559794;6865.656717164122;7000.92999070013;17094.145292735313;17263.39578114785;17278.049707266222;17323.566910827078;17328.913355433142;8054.315383192495;8078.192180781874;8098.595070246464;8099.959500202462;8109.918900811034;7354.926450735531;7464.440166987498;7475.177730449646;7591.354734847489;7656.9234307657325;5323.787048518094;5396.91904621431;5407.918881216785;5458.2904222450825;5629.718514074362;1421.6943357178257;1485.3115860292905;1509.1020842598637;1544.7219500490019;1579.2498563182626;892.8035832116924;892.8973168085623;901.1664210605164;924.8612708093705;928.0348437624795;7532;7584.696612135453;7607.277308655669;7715.344195743312;7751.92248077512;969.9272554558436;989.9703008909744;990.1830989433746;992.503748125937;997.1623835977814;4629.722216667011;4661.207594708908;4712.434507859079;4793.880152996203;4818.6626936114135;21211.15155393798;21287.46781330448;22022.229221977155;22211.111555537598;22422;21332.573348532944;21807.781922180577;22083.447913801963;22214.777852221596;22218.888905555694;10178.18574514037;10402.895971040343;10410.323328983743;10467.895321046692;10475.685729428129;9077.636894524408;9084.545772711206;9089.909100908906;9128.087191280818;9238.630454781733;18304.26782928721;18422.355217567856;18872.33946811855;19202.8079719201;19209.51976200606;19357.612847743247;19852.50368740793;19854.00729963467;20026.299079532146;20030.39908802738;12364.752704946031;12457.252564846138;12528.874711253135;12533.122681412213;12561.309127998047;13742.312884355542;13999.930000349934;14067.366968486647;14164.78752818688;14176.716465670836;11107.555697772323;11321.660350189508;11334.376609286568;11374.31754094757;11533.423328833693;10737.624183153865;10858.2399232053;10869.184811139037;11116.388598627153;11181.776364472827;9730.659426920023;9907.900920990985;9944.801103978025;9985;10010.14913732341;7067;7185.53294035886;7278.417726581966;7333.230010848784;7342.48602597813;7196.820079497845;7298.562086274628;7302.634868256672;7356;7410.62946852666 03:04:13 INFO - PID 3849 | |4;prototype.html;498.7481421631704;502.5075426082439;528.4636094364221;537.5726297593413;538.3270911360798;6367.235931688198;6372.553921225516;6376;6444.226692796865;6522.152120224364;6111.266648002234;6225.190725205717;6232.59488133271;6247.437730604256;6340.812419715191;5014.222795466709;5015.648904576681;5038.193888977764;5041.798328066882;5056.19100943849;4782.282657601353;4930.506949305077;4977.925331120036;4980.750962451881;5000.97499512502;4924.236743304794;4991.126552853254;5015.7898944675435;5024.120778863702;5051.3180720602795;4968.527989840959;4979.850604481835;4985.725785081819;5046.646734728571;5067.214581739836;4453.710508816916;4484.358688757121;4485.327200919855;4499;4530.86407407775;6224.439800417984;6278.340774218687;6282.591631543981;6308.2745484269435;6326.335734747831;5729.541636669056;5783.826485205451;5795.696453021779;5798.942010579883;5838.7080645967535;6698.129243198402;6776.559523630948;6798.558093723892;6850.6574671266735;6853.4174595159475;4847.5394837490385;4861.513848615145;4889.633277504166;4902.950970490285;4953.4798846121;7031.558530501235;7079.185893622196;7098.325659062432;7158.248383919666;7175.748848790267;5065.441103007322;5105.157648987875;5111.361079865017;5116.232565115262;5122.359705036871;5111.437741848394;5132.307138536325;5137.8715532112;5167.276581278624;5168.010316028356;5577.2191893135;5628.380878103405;5664.971675141598;5717.2281741965135;5788.913166302509;6583.275839657634;6681.465482761368;6690;6742.696578653972;6808.3191680832515;1634.3054201964117;1676.6562854614774;1680.9579760506085;1743.2329774899003;1755.9122043897753;1144.8797876223043;1187.8693343732182;1217.7807994561065;1230.993845030769;1238.7027113492697;5858.384869588717;5952.255968003913;5958.6333721447245;5998.430149135826;6056.909756243918;6683.4987375947;6756.729730810713;6789.2871248518195;6817.318268173242;6832.419244364285;5784.595078344475;5828.562857785683;5888.263967004124;5977.970110149421;6038.426349496791;3572.2676851245433;3587.5694916610173;3638.5633723953297;3669.541307336583;3674.2259594006355;6508.9023664645065;6621.1392518972225;6654.234763002317;6656.966715166393;6663.033860090357;6971.745085884488;7020.7542736004;7045.436365090781;7068.611226382598;7110.857782844314;6631.082323777522;6637.701303441357;6645.833854153685;6657.866842663119;6690.899636505554;6800.353966373186;6826.453883689193;6839.897401538881;6893.0694356261265;6914.377706006484;6711.697973591103;6799.354061364163;6800.421964133104;6823.3601151217645;6828.156397772673;6638.900416493853;6668.433183179484;6676.399124078856;6685.933140668531;6752.864942701217;4967.279744436964;4983.127952608352;5005.924911126263;5039.571636410946;5048.671836330626;6887.931120688729;6973.825654358681;7012.7545535906;7024.192217895007;7069.611171385417;6658.7336506539195;6661.2006559213205;6673.399394054559;6677.165354330708;6681.398674119353;6597.416620011067;6643.634600097041;6679.966600166968;6689.933100668931;6762.256151823197;6371.426571608577;6516.6415847128865;6550.279469258473;6579.046038324321;6585.901211481927;6463.385978332051;6538.215414150332;6577.375149360803;6624.840652885629;6687.431568316748;6409.436225509833;6445.581037232565;6456.838579035561;6491.642959637076;6495.317991611002 03:04:13 INFO - PID 3849 | |5;yui.html;868.8696695495674;903.4940433357319;916.5234078279299;919.83902817007;923.6767131503974;17747.290108395664;18438;18482.52139828814;18484.907575462104;18500.444986650404;17014.82985170148;17324.913375433138;17395.652086958286;17490.737638935425;17566.648667026682;999.090827347116;1009.7980403919208;1016.4307987526971;1016.7661437869294;1016.9288149829515;1015.8273093574073;1019.2202964731949;1020.5305559442628;1020.6478764826123;1020.7601213714771;1002.8846682631481;1024.5082360466981;1031.8555402243692;1035.0994634667811;1059.5496913811598;1019.6991887393236;1024.538957469138;1025.4411345816548;1025.7589466475372;1029.953652085658;756.4629113329543;757.9431542634325;758.5638258001616;758.916519182895;760.1334478694293;17695.73456398155;17696.91151544247;17753.467395978012;17770.46688599331;17896.194671239824;20851.478713032146;21801;22003.559928801333;22076.11695532193;22168.334949951364;17074.48776536706;17274.913625431916;17356.56608584783;17362.913185434118;17439.651206975916;3498.0904964709102;3511.5083888255663;3535.7878527288444;3540.805255710935;3543.539339885798;131026.344868276;131523;135137.999999999;136126;137899;14761.77857332141;14891.553253402415;14963.102213866985;15198.088114713153;15389.538313850602;30259.848700756356;31032.844835775675;31350.52974205389;31466.37067258642;31501.52747708786;8689.56552172389;8713.607887645072;8731.607077681392;8773.034966153718;8792.34057445694;4186.28833098374;4266.914661706811;4272.957270427318;4298.161858437637;4319.43847299849;4788.712677239377;4948.826791062295;4952.826651067196;5087.313212716236;5097.821576244888;964.6961207219844;990.2424645146468;1130.4630300607146;1174.7767924094394;1177.0759953436511;692.3042342445809;700.0619170311807;714.2571725405624;714.4641518860855;715.3847690985748;11629.360385178896;11757.412129393497;11958.103142264192;12727;12987.935060324828;660.6234446365526;668.0647094068246;676.82064252973;677.5460441504204;679.2121139478181;5844.649321040752;5925.247507503625;5942.940570594239;5967.194428752063;5996.070609055603;114136;114252;114272;118891;120157.39921300512;97785;99197.50401247948;102856;103129;104790;83595;84944.57527712322;87472;88197;88280;11844.644660660195;12743.362831858556;12804.423800928795;12838.871611283768;13071.085024048605;39413.408798868615;39931;40619.593804062766;41453.79273103615;41841.58158418377;65483;69813.30186698068;70051.29948700448;70603.64698176681;73234.90147647896;17651.823481765532;17912.462626120716;17929.910350448165;17945.641087178443;18041.1881465337;19626.214951401784;19781.40655780329;19875.900620496806;20137.597248054662;20407.795922041183;17071.40250091241;17611.207495662984;18033;18184.27262909469;18374.173162208;17665.735013975056;18057.819421805612;18180.636387272443;18716.438506844817;19811.504712382306;16817.831821682117;16909.830901690824;17447.738283926006;17556.38552650651;17579.29682812673;11954.880451195724;12319;12646.494140234656;12760.68098297513;12976.221426714428;13200.537981170613;13902.860971390157;13987.580372588838;14057.22685252321;14228.430862765374 03:04:13 INFO - PID 3849 | __end_tp_report 03:04:13 INFO - PID 3849 | __start_cc_report 03:04:13 INFO - PID 3849 | _x_x_mozilla_cycle_collect,371 03:04:13 INFO - PID 3849 | __end_cc_report 03:04:13 INFO - PID 3849 | __startTimestamp1504433053779__endTimestamp 03:04:13 INFO - PID 3849 | 03:04:13 INFO - PID 3849 | ------- Summary: start ------- 03:04:13 INFO - PID 3849 | Number of tests: 6 03:04:13 INFO - PID 3849 | 03:04:13 INFO - PID 3849 | [#0] dojo.html Cycles:165 Average:6348.06 Median:6561.38 stddev:747.79 (11.4%) stddev-sans-first:710.43 03:04:13 INFO - PID 3849 | Values: 3285.1 3302.4 3332.4 3376.0 3380.8 6307.2 6380.2 6401.3 6414.4 6419.2 6368.7 6384.7 6420.4 6426.2 6451.7 5882.6 5893.1 5919.9 5933.3 5937.5 5858.9 5940.5 5942.7 5968.5 5968.9 5911.9 6025.0 6025.8 6055.3 6060.6 5890.0 5962.6 6018.6 6037.0 6043.0 5753.7 5775.0 5808.2 5849.7 5879.0 6386.1 6441.6 6448.7 6477.2 6478.2 6903.4 6923.1 6926.8 6955.4 6994.1 6784.7 6963.3 6967.6 7073.4 7077.3 6366.7 6375.6 6412.3 6420.4 6467.7 7135.3 7140.8 7150.6 7151.4 7168.5 6640.5 6822.1 6828.1 6853.3 6862.7 6853.6 6882.7 6948.7 6961.2 6967.4 6807.6 6818.1 6898.0 6905.1 6942.5 6697.4 6786.3 6807.5 6812.6 6815.4 5355.6 5367.2 5370.1 5385.6 5400.6 4688.5 4694.5 4797.2 4801.8 4836.9 5959.5 6061.8 6099.9 6104.1 6109.0 6865.3 6928.1 6970.4 6985.0 6985.5 5806.1 5957.8 6019.5 6138.1 6139.5 5884.0 5892.9 5927.9 5971.1 5971.2 6686.4 6690.4 6758.1 6763.9 6775.4 6908.7 6937.6 7005.1 7007.0 7015.5 6589.6 6628.9 6738.3 6757.7 6766.1 6561.4 6598.9 6772.1 6807.5 6829.1 6852.2 6970.2 6988.5 6999.4 7005.4 6702.4 6710.2 6735.1 6742.9 6753.5 6642.4 6673.7 6712.7 6762.1 6766.2 6397.0 6553.1 6608.2 6699.4 6710.0 6682.9 6686.8 6741.1 6798.2 6815.9 6298.9 6400.1 6461.3 6469.6 6524.4 03:04:13 INFO - PID 3849 | 03:04:13 INFO - PID 3849 | [#1] ext.html Cycles:180 Average:38628.25 Median:3567.97 stddev:55366.06 (1551.8%) stddev-sans-first:55448.48 03:04:13 INFO - PID 3849 | Values: 790.8 792.6 821.7 839.4 858.7 41836.6 42482.8 42671.1 42735.6 42828.6 38757.8 38883.0 39197.8 39255.6 39363.0 6818.1 6903.7 6944.6 6949.8 6954.6 6784.5 6864.9 6877.2 6939.0 6961.3 6900.6 6945.4 6954.0 7685.4 7867.3 7719.0 7746.8 7808.1 7835.4 7848.5 7681.7 7745.6 7753.1 7759.2 7768.7 37829.0 38555.4 38573.8 38669.0 38680.4 3472.4 3566.7 3569.3 3571.4 3579.4 32206.2 32568.7 32579.2 32732.2 32769.3 5208.4 5219.7 5221.4 5269.7 5283.0 186838.0 188359.0 190189.0 190376.0 202051.0 49661.0 49718.0 49739.3 49899.5 50085.0 51231.7 51237.7 51353.7 51581.7 51726.7 15885.4 15970.0 15974.3 15987.3 15988.3 12892.0 13050.5 13058.8 13068.9 13083.8 14487.2 14559.2 14591.1 14618.3 14684.3 5912.4 5926.2 6015.0 6021.5 6035.1 990.1 1014.7 1016.8 1023.9 1029.9 28376.3 29321.4 29368.7 29522.1 29597.4 1101.6 1108.1 1111.3 1112.6 1135.8 12446.8 12575.7 12762.9 12786.3 15851.6 239963.8 240187.0 241410.0 241659.0 242474.0 57336.4 57941.7 58045.0 58400.4 58433.4 1391.1 1461.0 1463.1 1473.0 1474.9 27511.1 27573.4 27877.0 27877.0 28350.3 39227.6 39272.2 39578.0 39699.0 39827.2 202865.0 204012.0 205163.0 205520.0 206487.0 31817.7 32787.7 34537.5 34635.0 35532.0 40909.4 43664.8 43669.6 43823.6 43993.8 31909.7 31921.5 32465.5 32473.2 33234.7 31438.4 31483.0 31510.8 31643.7 31869.2 36268.6 36556.0 36698.3 36818.0 36855.1 30484.4 30843.8 30915.0 31183.8 31194.5 22896.3 23245.8 23347.3 23481.5 23654.4 03:04:13 INFO - PID 3849 | 03:04:13 INFO - PID 3849 | [#2] jquery.html Cycles:180 Average:36838.58 Median:22554.55 stddev:90876.12 (402.9%) stddev-sans-first:91090.22 03:04:13 INFO - PID 3849 | Values: 555.0 565.9 567.7 571.9 591.5 21832.8 22477.4 22529.2 22548.9 22560.2 23332.4 25383.4 25498.7 25737.9 25788.7 6569.7 6651.7 6734.5 6734.7 6740.3 6426.5 6580.2 6598.3 6626.5 6772.1 6676.7 6711.4 6733.7 6738.5 6743.3 6598.2 6696.4 6711.1 6714.9 6720.1 6173.3 6224.6 6224.8 6229.1 6239.9 28172.3 28221.0 28315.9 28492.9 28927.4 1991.9 2026.6 2041.2 2043.4 2045.4 19127.5 19767.0 19769.0 19790.1 19867.5 2233.8 2262.3 2265.5 2268.7 2371.3 135328.0 136488.0 138141.0 138925.0 139013.3 13734.9 14448.3 14577.2 14624.9 14659.5 22842.3 23162.8 23164.1 23213.0 23468.8 9918.9 10288.2 10364.7 10465.5 10513.3 9415.0 9418.0 9609.0 9661.9 9686.0 7522.0 8919.6 9064.6 9135.3 9210.8 2627.3 2669.5 2671.6 2673.8 2706.0 961.2 983.8 986.8 1005.9 1007.8 19049.9 19357.0 19393.5 19505.5 19788.9 1050.7 1059.2 1069.3 1072.9 1073.7 10594.5 10663.8 10686.7 10756.8 10973.6 538329.0 539383.0 544368.0 544733.0 545073.0 121037.0 124913.0 125108.4 126236.4 127654.0 1208.3 1214.6 1252.8 1263.5 1264.0 20479.0 20645.4 20733.7 21107.5 21170.6 56145.4 56158.7 57027.7 57523.4 58792.7 72485.6 72947.6 72986.3 73283.0 74019.6 12125.6 12579.1 14204.4 14487.6 20496.4 23761.9 24398.6 24488.0 24700.5 24784.0 18944.2 19147.4 19200.7 19474.0 19553.2 16952.4 17058.4 17090.0 17286.4 17803.8 18324.4 19605.0 19682.5 19683.7 19685.5 19827.7 20342.6 20359.1 20406.0 20452.5 18765.2 18808.4 19084.2 19095.2 19255.2 03:04:13 INFO - PID 3849 | 03:04:13 INFO - PID 3849 | [#3] mootools.html Cycles:180 Average:9444.63 Median:2779.08 stddev:6755.24 (243.1%) stddev-sans-first:6746.58 03:04:13 INFO - PID 3849 | Values: 1314.9 1363.4 1367.9 1371.1 1379.4 14739.8 15190.5 15241.9 15355.8 15398.5 15039.5 16026.2 16091.2 16102.5 16119.8 2772.1 2813.8 2818.4 2836.3 2837.4 2722.1 2808.3 2816.2 2817.6 2818.7 2604.6 2827.8 2827.9 2829.4 2831.7 2780.2 2784.7 2797.6 2816.7 2870.5 2776.3 2778.0 2803.8 2806.5 2808.4 14720.1 15124.2 15145.6 15196.2 15225.7 8127.0 8335.9 8357.6 8360.4 8405.9 5246.1 5479.2 5528.9 5600.0 5697.7 1936.5 1961.9 1964.1 1986.2 2007.7 26356.0 26616.7 26663.9 26670.7 26677.1 6799.0 6835.2 6840.1 6865.7 7000.9 17094.1 17263.4 17278.0 17323.6 17328.9 8054.3 8078.2 8098.6 8100.0 8109.9 7354.9 7464.4 7475.2 7591.4 7656.9 5323.8 5396.9 5407.9 5458.3 5629.7 1421.7 1485.3 1509.1 1544.7 1579.2 892.8 892.9 901.2 924.9 928.0 7532.0 7584.7 7607.3 7715.3 7751.9 969.9 990.0 990.2 992.5 997.2 4629.7 4661.2 4712.4 4793.9 4818.7 21211.2 21287.5 22022.2 22211.1 22422.0 21332.6 21807.8 22083.4 22214.8 22218.9 10178.2 10402.9 10410.3 10467.9 10475.7 9077.6 9084.5 9089.9 9128.1 9238.6 18304.3 18422.4 18872.3 19202.8 19209.5 19357.6 19852.5 19854.0 20026.3 20030.4 12364.8 12457.3 12528.9 12533.1 12561.3 13742.3 13999.9 14067.4 14164.8 14176.7 11107.6 11321.7 11334.4 11374.3 11533.4 10737.6 10858.2 10869.2 11116.4 11181.8 9730.7 9907.9 9944.8 9985.0 10010.1 7067.0 7185.5 7278.4 7333.2 7342.5 7196.8 7298.6 7302.6 7356.0 7410.6 03:04:13 INFO - PID 3849 | 03:04:13 INFO - PID 3849 | [#4] prototype.html Cycles:180 Average:5607.25 Median:6280.47 stddev:1599.79 (25.5%) stddev-sans-first:1557.65 03:04:13 INFO - PID 3849 | Values: 498.7 502.5 528.5 537.6 538.3 6367.2 6372.6 6376.0 6444.2 6522.2 6111.3 6225.2 6232.6 6247.4 6340.8 5014.2 5015.6 5038.2 5041.8 5056.2 4782.3 4930.5 4977.9 4980.8 5001.0 4924.2 4991.1 5015.8 5024.1 5051.3 4968.5 4979.9 4985.7 5046.6 5067.2 4453.7 4484.4 4485.3 4499.0 4530.9 6224.4 6278.3 6282.6 6308.3 6326.3 5729.5 5783.8 5795.7 5798.9 5838.7 6698.1 6776.6 6798.6 6850.7 6853.4 4847.5 4861.5 4889.6 4903.0 4953.5 7031.6 7079.2 7098.3 7158.2 7175.7 5065.4 5105.2 5111.4 5116.2 5122.4 5111.4 5132.3 5137.9 5167.3 5168.0 5577.2 5628.4 5665.0 5717.2 5788.9 6583.3 6681.5 6690.0 6742.7 6808.3 1634.3 1676.7 1681.0 1743.2 1755.9 1144.9 1187.9 1217.8 1231.0 1238.7 5858.4 5952.3 5958.6 5998.4 6056.9 6683.5 6756.7 6789.3 6817.3 6832.4 5784.6 5828.6 5888.3 5978.0 6038.4 3572.3 3587.6 3638.6 3669.5 3674.2 6508.9 6621.1 6654.2 6657.0 6663.0 6971.7 7020.8 7045.4 7068.6 7110.9 6631.1 6637.7 6645.8 6657.9 6690.9 6800.4 6826.5 6839.9 6893.1 6914.4 6711.7 6799.4 6800.4 6823.4 6828.2 6638.9 6668.4 6676.4 6685.9 6752.9 4967.3 4983.1 5005.9 5039.6 5048.7 6887.9 6973.8 7012.8 7024.2 7069.6 6658.7 6661.2 6673.4 6677.2 6681.4 6597.4 6643.6 6680.0 6689.9 6762.3 6371.4 6516.6 6550.3 6579.0 6585.9 6463.4 6538.2 6577.4 6624.8 6687.4 6409.4 6445.6 6456.8 6491.6 6495.3 03:04:13 INFO - PID 3849 | 03:04:13 INFO - PID 3849 | [#5] yui.html Cycles:180 Average:24064.66 Median:18119.23 stddev:33645.44 (185.7%) stddev-sans-first:33694.74 03:04:13 INFO - PID 3849 | Values: 868.9 903.5 916.5 919.8 923.7 17747.3 18438.0 18482.5 18484.9 18500.4 17014.8 17324.9 17395.7 17490.7 17566.6 999.1 1009.8 1016.4 1016.8 1016.9 1015.8 1019.2 1020.5 1020.6 1020.8 1002.9 1024.5 1031.9 1035.1 1059.5 1019.7 1024.5 1025.4 1025.8 1030.0 756.5 757.9 758.6 758.9 760.1 17695.7 17696.9 17753.5 17770.5 17896.2 20851.5 21801.0 22003.6 22076.1 22168.3 17074.5 17274.9 17356.6 17362.9 17439.7 3498.1 3511.5 3535.8 3540.8 3543.5 131026.3 131523.0 135138.0 136126.0 137899.0 14761.8 14891.6 14963.1 15198.1 15389.5 30259.8 31032.8 31350.5 31466.4 31501.5 8689.6 8713.6 8731.6 8773.0 8792.3 4186.3 4266.9 4273.0 4298.2 4319.4 4788.7 4948.8 4952.8 5087.3 5097.8 964.7 990.2 1130.5 1174.8 1177.1 692.3 700.1 714.3 714.5 715.4 11629.4 11757.4 11958.1 12727.0 12987.9 660.6 668.1 676.8 677.5 679.2 5844.6 5925.2 5942.9 5967.2 5996.1 114136.0 114252.0 114272.0 118891.0 120157.4 97785.0 99197.5 102856.0 103129.0 104790.0 83595.0 84944.6 87472.0 88197.0 88280.0 11844.6 12743.4 12804.4 12838.9 13071.1 39413.4 39931.0 40619.6 41453.8 41841.6 65483.0 69813.3 70051.3 70603.6 73234.9 17651.8 17912.5 17929.9 17945.6 18041.2 19626.2 19781.4 19875.9 20137.6 20407.8 17071.4 17611.2 18033.0 18184.3 18374.2 17665.7 18057.8 18180.6 18716.4 19811.5 16817.8 16909.8 17447.7 17556.4 17579.3 11954.9 12319.0 12646.5 12760.7 12976.2 13200.5 13902.9 13987.6 14057.2 14228.4 03:04:13 INFO - PID 3849 | -------- Summary: end -------- 03:04:13 INFO - PID 3849 | 03:04:14 INFO - TEST-INFO | 3849: exit 0 03:04:14 INFO - TEST-OK | dromaeo_css | took 1118230ms 03:04:14 INFO - TEST-START | kraken 03:04:14 INFO - Initialising browser for kraken test... 03:04:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46539/getInfo.html -profile /tmp/tmpKsSXAm/profile 03:04:14 INFO - TEST-INFO | started process 4232 (/builds/slave/test/build/application/firefox/firefox http://localhost:46539/getInfo.html) 03:04:20 INFO - TEST-INFO | 4232: exit 0 03:04:21 INFO - Browser initialized. 03:04:21 INFO - Running cycle 1/1 for kraken test... 03:04:21 INFO - Using env: {'DISPLAY': ':0', 03:04:21 INFO - 'HOME': '/home/cltbld', 03:04:21 INFO - 'JSGC_DISABLE_POISONING': '1', 03:04:21 INFO - 'LANG': 'en_US.UTF-8', 03:04:21 INFO - 'LANGUAGE': 'en_US:en', 03:04:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:04:21 INFO - 'LOGNAME': 'cltbld', 03:04:21 INFO - 'MAIL': '/var/mail/cltbld', 03:04:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:04:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:04:21 INFO - 'MOZ_ACCELERATED': '1', 03:04:21 INFO - 'MOZ_CRASHREPORTER': '1', 03:04:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:04:21 INFO - 'MOZ_NO_REMOTE': '1', 03:04:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:04:21 INFO - 'MOZ_WEBRENDER': '1', 03:04:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:21 INFO - 'NO_EM_RESTART': '1', 03:04:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:21 INFO - 'PWD': '/builds/slave/test', 03:04:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:04:21 INFO - 'RUST_BACKTRACE': 'full', 03:04:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:04:21 INFO - 'SHELL': '/bin/bash', 03:04:21 INFO - 'SHLVL': '1', 03:04:21 INFO - 'TERM': 'linux', 03:04:21 INFO - 'TMOUT': '86400', 03:04:21 INFO - 'USER': 'cltbld', 03:04:21 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914', 03:04:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:21 INFO - '_': '/tools/buildbot/bin/python'} 03:04:21 INFO - TEST-INFO | started process 4429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKsSXAm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:04:22 INFO - PID 4429 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:04:22 INFO - PID 4429 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:04:22 INFO - PID 4429 | 03:04:22 INFO - PID 4429 | (/builds/slave/test/build/application/firefox/firefox:4491): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:04:22 INFO - PID 4429 | 03:04:32 INFO - PID 4429 | RSS: Main: 199819264 03:04:32 INFO - PID 4429 | 03:04:35 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46539/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:36 INFO - PID 4429 | RSS: Main: 204906496 03:04:36 INFO - PID 4429 | 03:04:36 INFO - PID 4429 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:04:39 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46539/tests/kraken/driver.html?testName=audio-dft) 03:04:39 INFO - PID 4429 | RSS: Main: 210984960 03:04:39 INFO - PID 4429 | 03:04:42 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46539/tests/kraken/driver.html?testName=audio-fft) 03:04:43 INFO - PID 4429 | RSS: Main: 204898304 03:04:43 INFO - PID 4429 | 03:04:45 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46539/tests/kraken/driver.html?testName=audio-oscillator) 03:04:46 INFO - PID 4429 | RSS: Main: 206045184 03:04:46 INFO - PID 4429 | 03:04:48 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46539/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:04:49 INFO - PID 4429 | RSS: Main: 206176256 03:04:49 INFO - PID 4429 | 03:04:52 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46539/tests/kraken/driver.html?testName=imaging-darkroom) 03:04:52 INFO - PID 4429 | RSS: Main: 206356480 03:04:52 INFO - PID 4429 | 03:04:56 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46539/tests/kraken/driver.html?testName=imaging-desaturate) 03:04:57 INFO - PID 4429 | RSS: Main: 206376960 03:04:57 INFO - PID 4429 | 03:04:59 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46539/tests/kraken/driver.html?testName=json-parse-financial) 03:05:00 INFO - PID 4429 | RSS: Main: 206917632 03:05:00 INFO - PID 4429 | 03:05:02 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46539/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:05:02 INFO - PID 4429 | RSS: Main: 207032320 03:05:02 INFO - PID 4429 | 03:05:04 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46539/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:05:05 INFO - PID 4429 | RSS: Main: 206217216 03:05:05 INFO - PID 4429 | 03:05:07 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46539/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:05:08 INFO - PID 4429 | RSS: Main: 205451264 03:05:08 INFO - PID 4429 | 03:05:10 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46539/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:05:11 INFO - PID 4429 | RSS: Main: 207626240 03:05:11 INFO - PID 4429 | 03:05:14 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46539/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:05:14 INFO - PID 4429 | RSS: Main: 207339520 03:05:14 INFO - PID 4429 | 03:05:16 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:46539/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46539/tests/kraken/driver.html?testName=ai-astar) 03:05:17 INFO - PID 4429 | RSS: Main: 209244160 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | __start_tp_report 03:05:17 INFO - PID 4429 | _x_x_mozilla_page_load 03:05:17 INFO - PID 4429 | _x_x_mozilla_page_load_details 03:05:17 INFO - PID 4429 | |i|pagename|runs| 03:05:17 INFO - PID 4429 | |0;ai-astar;99;117;110;111;117;109;114;105;108;114 03:05:17 INFO - PID 4429 | |1;audio-beat-detection;145;122;148;180;118;147;155;120;171;122 03:05:17 INFO - PID 4429 | |2;audio-dft;174;171;167;164;166;164;168;165;164;163 03:05:17 INFO - PID 4429 | |3;audio-fft;74;78;74;176;74;81;83;74;161;78 03:05:17 INFO - PID 4429 | |4;audio-oscillator;79;88;127;79;86;84;132;80;89;140 03:05:17 INFO - PID 4429 | |5;imaging-gaussian-blur;122;119;122;115;115;115;115;116;114;119 03:05:17 INFO - PID 4429 | |6;imaging-darkroom;148;148;149;156;148;152;149;148;159;148 03:05:17 INFO - PID 4429 | |7;imaging-desaturate;95;102;101;95;95;94;98;95;94;102 03:05:17 INFO - PID 4429 | |8;json-parse-financial;57;52;52;53;55;61;53;54;52;52 03:05:17 INFO - PID 4429 | |9;json-stringify-tinderbox;51;44;38;48;41;38;38;39;41;39 03:05:17 INFO - PID 4429 | |10;stanford-crypto-aes;77;75;78;85;76;76;82;76;78;74 03:05:17 INFO - PID 4429 | |11;stanford-crypto-ccm;125;120;134;119;130;120;128;123;123;170 03:05:17 INFO - PID 4429 | |12;stanford-crypto-pbkdf2;169;176;170;172;171;173;177;174;169;175 03:05:17 INFO - PID 4429 | |13;stanford-crypto-sha256-iterative;64;66;68;68;65;68;80;63;67;63 03:05:17 INFO - PID 4429 | __end_tp_report 03:05:17 INFO - PID 4429 | __start_cc_report 03:05:17 INFO - PID 4429 | _x_x_mozilla_cycle_collect,943 03:05:17 INFO - PID 4429 | __end_cc_report 03:05:17 INFO - PID 4429 | __startTimestamp1504433117043__endTimestamp 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | ------- Summary: start ------- 03:05:17 INFO - PID 4429 | Number of tests: 14 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#0] ai-astar Cycles:10 Average:110.40 Median:114.00 stddev:5.58 (4.9%) stddev-sans-first:4.12 03:05:17 INFO - PID 4429 | Values: 99.0 117.0 110.0 111.0 117.0 109.0 114.0 105.0 108.0 114.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:147.50 stddev:22.05 (15.0%) stddev-sans-first:23.38 03:05:17 INFO - PID 4429 | Values: 145.0 122.0 148.0 180.0 118.0 147.0 155.0 120.0 171.0 122.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#2] audio-dft Cycles:10 Average:166.60 Median:166.50 stddev:3.53 (2.1%) stddev-sans-first:2.54 03:05:17 INFO - PID 4429 | Values: 174.0 171.0 167.0 164.0 166.0 164.0 168.0 165.0 164.0 163.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#3] audio-fft Cycles:10 Average:95.30 Median:76.00 stddev:38.87 (51.1%) stddev-sans-first:40.46 03:05:17 INFO - PID 4429 | Values: 74.0 78.0 74.0 176.0 74.0 81.0 83.0 74.0 161.0 78.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:82.00 stddev:24.33 (29.7%) stddev-sans-first:24.77 03:05:17 INFO - PID 4429 | Values: 79.0 88.0 127.0 79.0 86.0 84.0 132.0 80.0 89.0 140.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.50 stddev:3.05 (2.6%) stddev-sans-first:2.69 03:05:17 INFO - PID 4429 | Values: 122.0 119.0 122.0 115.0 115.0 115.0 115.0 116.0 114.0 119.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:149.00 stddev:3.95 (2.7%) stddev-sans-first:4.09 03:05:17 INFO - PID 4429 | Values: 148.0 148.0 149.0 156.0 148.0 152.0 149.0 148.0 159.0 148.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:95.00 stddev:3.35 (3.5%) stddev-sans-first:3.46 03:05:17 INFO - PID 4429 | Values: 95.0 102.0 101.0 95.0 95.0 94.0 98.0 95.0 94.0 102.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:53.50 stddev:2.92 (5.5%) stddev-sans-first:2.91 03:05:17 INFO - PID 4429 | Values: 57.0 52.0 52.0 53.0 55.0 61.0 53.0 54.0 52.0 52.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#9] json-stringify-tinderbox Cycles:10 Average:41.70 Median:41.00 stddev:4.57 (11.2%) stddev-sans-first:3.39 03:05:17 INFO - PID 4429 | Values: 51.0 44.0 38.0 48.0 41.0 38.0 38.0 39.0 41.0 39.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:77.50 stddev:3.37 (4.3%) stddev-sans-first:3.56 03:05:17 INFO - PID 4429 | Values: 77.0 75.0 78.0 85.0 76.0 76.0 82.0 76.0 78.0 74.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#11] stanford-crypto-ccm Cycles:10 Average:129.20 Median:126.50 stddev:15.12 (12.0%) stddev-sans-first:15.96 03:05:17 INFO - PID 4429 | Values: 125.0 120.0 134.0 119.0 130.0 120.0 128.0 123.0 123.0 170.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:173.50 stddev:2.88 (1.7%) stddev-sans-first:2.74 03:05:17 INFO - PID 4429 | Values: 169.0 176.0 170.0 172.0 171.0 173.0 177.0 174.0 169.0 175.0 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:4.92 (7.3%) stddev-sans-first:5.08 03:05:17 INFO - PID 4429 | Values: 64.0 66.0 68.0 68.0 65.0 68.0 80.0 63.0 67.0 63.0 03:05:17 INFO - PID 4429 | -------- Summary: end -------- 03:05:17 INFO - PID 4429 | 03:05:17 INFO - PID 4429 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:05:17 INFO - TEST-INFO | 4429: exit 0 03:05:18 INFO - TEST-OK | kraken | took 63250ms 03:05:18 INFO - SUITE-END | took 1181s 03:05:18 INFO - Completed test suite (00:19:41) 03:05:18 INFO - javascript benchmark 03:05:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8667.680975816384, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3285.080177550287, 3302.356040572088, 3332.350191712617, 3376.0, 3380.847861846218, 6307.243130824308, 6380.202474690664, 6401.263854656716, 6414.422701956829, 6419.197600299963, 6368.681565921708, 6384.712687929043, 6420.390062944025, 6426.228852537702, 6451.709673064712, 5882.588218824684, 5893.145493903381, 5919.940800592003, 5933.258342707161, 5937.4656280934705, 5858.853528661775, 5940.465358117768, 5942.732577034044, 5968.522518198533, 5968.940310596882, 5911.852203694921, 6024.96987515064, 6025.8192254232445, 6055.27336719592, 6060.575759696823, 5889.998700220973, 5962.612430192024, 6018.578699491038, 6037.0, 6042.96978515109, 5753.741081651336, 5775.047117225653, 5808.1578171165365, 5849.736761845727, 5878.970605146989, 6386.0740192672265, 6441.581297215666, 6448.742050317982, 6477.22273327199, 6478.190226221723, 6903.413209877166, 6923.065386172852, 6926.82244018518, 6955.408790252784, 6994.0558024666525, 6784.6946887389695, 6963.268856770017, 6967.6167810770385, 7073.434125269966, 7077.327653872874, 6366.681665916685, 6375.6174629522375, 6412.3267056959285, 6420.422162005395, 6467.676616169267, 7135.322144396274, 7140.7857764267155, 7150.606716630531, 7151.356377926011, 7168.53404528704, 6640.535162538576, 6822.147231596051, 6828.146481689789, 6853.348931851466, 6862.656867156722, 6853.588784672936, 6882.655867206719, 6948.722051117999, 6961.23426423093, 6967.407770339477, 6807.591544507246, 6818.147731533559, 6897.9655101725175, 6905.136857892763, 6942.548734332252, 6697.3637504437, 6786.321367863175, 6807.4894382921475, 6812.591244525344, 6815.42068924147, 5355.625106242525, 5367.221752845893, 5370.087085195526, 5385.569154467633, 5400.62195646301, 4688.460827004938, 4694.460137084211, 4797.160496913027, 4801.807927682923, 4836.903261934812, 5959.4934430573885, 6061.787837425757, 6099.878002439927, 6104.084387341934, 6109.0, 6865.347791959756, 6928.064711264015, 6970.407515361149, 6985.0, 6985.511014228955, 5806.129080637938, 5957.791477298274, 6019.548533859978, 6138.14066030756, 6139.539534534928, 5883.970580147072, 5892.941070589325, 5927.940720592739, 5971.104334349882, 5971.164037034819, 6686.431653309523, 6690.431313338323, 6758.05387245786, 6763.898541521881, 6775.3902148805855, 6908.654567271717, 6937.583744975318, 7005.124359455068, 7007.019017337679, 7015.543989640657, 6589.604623722592, 6628.90056649141, 6738.2924792898, 6757.729690812313, 6766.120404347404, 6561.376669216417, 6598.901016484852, 6772.051912732123, 6807.455403567802, 6829.112215411965, 6852.246252912076, 6970.198427180939, 6988.510804243654, 6999.440044796303, 7005.369516743313, 6702.396784289437, 6710.228323742832, 6735.090762747162, 6742.8651426972165, 6753.459723222229, 6642.4021838034805, 6673.699683514156, 6712.731490740316, 6762.087118238975, 6766.18805743314, 6396.968015159894, 6553.115329430657, 6608.240052394036, 6699.430548403246, 6710.0, 6682.899756503559, 6686.83282917931, 6741.1236539249585, 6798.184217893884, 6815.897761533482, 6298.937010629835, 6400.103985442135, 6461.256955450183, 6469.5794773339585, 6524.412802847766], "value": 6292.928727640862, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.7509134622593, 792.6393490961613, 821.7000794709929, 839.391441205126, 858.7423772868138, 41836.58163418365, 42482.78758606206, 42671.14657706852, 42735.57264427355, 42828.57171428285, 38757.806210968905, 38883.0, 39197.80401097998, 39255.60744392555, 39363.0, 6818.147731533571, 6903.689333979958, 6944.6180460074675, 6949.791506254821, 6954.617496037716, 6784.457243420539, 6864.931350686479, 6877.243503214668, 6938.965305173492, 6961.269066747995, 6900.551464154839, 6945.374916257536, 6953.95690646402, 7685.423593230502, 7867.291943725064, 7718.961405192994, 7746.806329841715, 7808.102068262166, 7835.373170146375, 7848.489848159851, 7681.692732290701, 7745.6127193640095, 7753.108392534874, 7759.224077592252, 7768.65041073153, 37829.0, 38555.42166867499, 38573.80713096417, 38669.0, 38680.41979370312, 3472.44440889459, 3566.661167189113, 3569.28614277143, 3571.357155711971, 3579.4451859961487, 32206.194845128826, 32568.6743132568, 32579.185520361945, 32732.181695457566, 32769.344613107845, 5208.427073021964, 5219.739013049332, 5221.4256431792855, 5269.710165940872, 5282.97358513205, 186838.0, 188359.0, 190189.0, 190376.0, 202051.0, 49661.0, 49718.0, 49739.25391119136, 49899.501004989484, 50085.0, 51231.74384128056, 51237.74381128071, 51353.74323128435, 51581.742091290056, 51726.741366292925, 15885.444009459614, 15970.041797491955, 15974.281157347947, 15987.280572374271, 15988.280527376295, 12892.033097517537, 13050.54323098687, 13058.804117938238, 13068.869311306955, 13083.803742943863, 14487.20320382389, 14559.19924404168, 14591.124532528082, 14618.342174602169, 14684.26578671062, 5912.398544640345, 5926.170336152942, 6014.969925150346, 6021.488173505301, 6035.1249068885645, 990.1089019882164, 1014.7463134216446, 1016.8169729448771, 1023.918086553074, 1029.8712660917386, 28376.290592734935, 29321.413571728444, 29368.706312937025, 29522.11433656994, 29597.408051838844, 1101.6474728087096, 1108.0692218536467, 1111.2665640677114, 1112.5994641928899, 1135.7501349703052, 12446.751064978649, 12575.748485030432, 12762.936185319015, 12786.296753678636, 15851.603709907344, 239963.80018100148, 240187.0, 241410.0, 241659.0, 242474.0, 57336.42663573311, 57941.71029144912, 58045.0, 58400.415995839496, 58433.41566584365, 1391.1444461656008, 1460.9700161386197, 1463.0855715178013, 1473.035161968912, 1474.86726194643, 27511.08031169456, 27573.448531028866, 27877.0, 27877.0, 28350.29124271827, 39227.607723922396, 39272.214555709295, 39578.0, 39699.0, 39827.203455931296, 202865.0, 204012.0, 205163.0, 205520.0, 206487.0, 31817.681823181472, 32787.67212327846, 34537.48193777146, 34635.0, 35532.0, 40909.38635920404, 43664.781676091414, 43669.56330436782, 43823.56176438322, 43993.78003109964, 31909.68090319067, 31921.521177181898, 32465.51301730523, 32473.188170295933, 33234.66765332316, 31438.37123257568, 31483.0, 31510.842445787624, 31643.683563164075, 31869.20326991844, 36268.63731362758, 36556.0, 36698.26603467969, 36818.0, 36855.07862303464, 30484.390312193187, 30843.845780770953, 30915.0, 31183.84408078036, 31194.532082018333, 22896.313110606043, 23245.76754232436, 23347.299581012598, 23481.530369392174, 23654.408639784142], "value": 17416.453245124165, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.9694766787825, 565.8626161415553, 567.6594043573856, 571.9227904232929, 591.5208680968415, 21832.781672183275, 22477.438064048387, 22529.21147759829, 22548.88725556374, 22560.21039263626, 23332.41668958277, 25383.365415864613, 25498.74501254987, 25737.87131064342, 25788.742112578868, 6569.671516424183, 6651.700673469705, 6734.494912881553, 6734.730610775563, 6740.292269311725, 6426.485881129522, 6580.210374755012, 6598.274189839114, 6626.5030122740745, 6772.051912732221, 6676.666166691671, 6711.4295284900845, 6733.730650773963, 6738.460923126163, 6743.258241593421, 6598.175228096488, 6696.430803381694, 6711.127553418073, 6714.899276510881, 6720.059191713164, 6173.32093469718, 6224.62652240867, 6224.782132625382, 6229.09678096678, 6239.906401403982, 28172.295692607644, 28221.012264570847, 28315.85842070797, 28492.857535712395, 28927.42145157085, 1991.8804871707744, 2026.5946810637784, 2041.2141325589607, 2043.3665563675308, 2045.3863840847835, 19127.521811954673, 19767.0, 19769.0, 19790.10944507486, 19867.50331241716, 2233.798958093779, 2262.2534563593977, 2265.501589650291, 2268.727752669674, 2371.312319427364, 135328.0, 136488.0, 138140.99999999898, 138925.0, 139013.3049334747, 13734.939759036224, 14448.277586120863, 14577.198254096127, 14624.85375146235, 14659.48691795782, 22842.31473055811, 23162.768372316397, 23164.073437062667, 23213.0, 23468.765312347, 9918.900810991941, 10288.176945844314, 10364.740881477872, 10465.529051192714, 10513.316634418738, 9414.95292523533, 9417.952910235404, 9609.0, 9661.903380966241, 9685.965947151037, 7521.984532088098, 8919.554022298858, 9064.637414503479, 9135.269178465707, 9210.81578368429, 2627.2906315294763, 2669.452762183748, 2671.639328690602, 2673.8262012969094, 2705.971730742305, 961.1974001708512, 983.7786498038028, 986.8322385194534, 1005.9195264378832, 1007.8488226765897, 19049.904750476435, 19357.032148392525, 19393.51516212106, 19505.512362191057, 19788.90105549463, 1050.669039252633, 1059.21618002679, 1069.2889228662852, 1072.9463526823627, 1073.7315671082229, 10594.523246453928, 10663.84004239937, 10686.679399618024, 10756.784864302826, 10973.561057557768, 538329.0, 539383.0, 544368.0, 544733.0, 545073.0, 121037.0, 124913.0, 125108.37445812895, 126236.36881815532, 127654.0, 1208.2871106047298, 1214.5627574073326, 1252.7557126360273, 1263.5451237554473, 1263.9873601264053, 20479.0, 20645.380638580868, 20733.68899466479, 21107.47231319168, 21170.576588467837, 56145.43854561402, 56158.71920640371, 57027.714861425426, 57523.42476575348, 58792.70603647125, 72485.6375718118, 72947.63526182548, 72986.27013729795, 73283.0, 74019.62990185014, 12125.636230913087, 12579.119461637598, 14204.431822727389, 14487.565373038826, 20496.38510844677, 23761.881190594628, 24398.634020489353, 24488.0, 24700.50598988046, 24784.0, 18944.242230311185, 19147.425577232705, 19200.71198932045, 19474.0, 19553.217871285557, 16952.406665766637, 17058.402955896483, 17090.0, 17286.394976175772, 17803.821961780217, 18324.358647447276, 19605.0, 19682.50793730111, 19683.70474442856, 19685.50786230356, 19827.702584461535, 20342.59314813725, 20359.083841227475, 20406.0, 20452.488687782923, 18765.155567999747, 18808.435746927615, 19084.236630534622, 19095.23619055278, 19255.22979080821], "value": 12268.486303961186, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.9013823963203, 1363.3592211660527, 1367.9110857794242, 1371.053972758797, 1379.4206433298014, 14739.778903316446, 15190.468333608298, 15241.933064685463, 15355.769663455043, 15398.461053863117, 15039.548813535612, 16026.198690065508, 16091.195440228, 16102.516924492284, 16119.83880161198, 2772.085211880085, 2813.831170129799, 2818.3658676797763, 2836.347640042781, 2837.3615936414244, 2722.101706436881, 2808.2979255186206, 2816.2396153038667, 2817.577363395497, 2818.7463128318445, 2604.622329762183, 2827.8444685542286, 2827.8586070696488, 2829.39168078863, 2831.688514263429, 2780.1659502149273, 2784.7354501322343, 2797.5523916173315, 2816.690164081949, 2870.5120129577917, 2776.3475583237923, 2777.986110069457, 2803.8317700937805, 2806.4667713134645, 2808.36811717364, 14720.116792992454, 15124.168170750605, 15145.621359465991, 15196.164210968393, 15225.695486090326, 8127.0, 8335.916640833575, 8357.582120893992, 8360.414770966036, 8405.915940840636, 5246.134387826004, 5479.1781232815, 5528.944710552883, 5600.047991841397, 5697.743601537941, 1936.5352315444334, 1961.901904904749, 1964.1259639460445, 1986.1558837494008, 2007.65869802134, 26356.0, 26616.73383266162, 26663.866680666666, 26670.733292667213, 26677.06630267951, 6798.966005170041, 6835.213950395669, 6840.11078559794, 6865.656717164122, 7000.92999070013, 17094.145292735313, 17263.39578114785, 17278.049707266222, 17323.566910827078, 17328.913355433142, 8054.315383192495, 8078.192180781874, 8098.595070246464, 8099.959500202462, 8109.918900811034, 7354.926450735531, 7464.440166987498, 7475.177730449646, 7591.354734847489, 7656.9234307657325, 5323.787048518094, 5396.91904621431, 5407.918881216785, 5458.2904222450825, 5629.718514074362, 1421.6943357178257, 1485.3115860292905, 1509.1020842598637, 1544.7219500490019, 1579.2498563182626, 892.8035832116924, 892.8973168085623, 901.1664210605164, 924.8612708093705, 928.0348437624795, 7532.0, 7584.696612135453, 7607.277308655669, 7715.344195743312, 7751.92248077512, 969.9272554558436, 989.9703008909744, 990.1830989433746, 992.503748125937, 997.1623835977814, 4629.722216667011, 4661.207594708908, 4712.434507859079, 4793.880152996203, 4818.6626936114135, 21211.15155393798, 21287.46781330448, 22022.229221977155, 22211.111555537598, 22422.0, 21332.573348532944, 21807.781922180577, 22083.447913801963, 22214.777852221596, 22218.888905555694, 10178.18574514037, 10402.895971040343, 10410.323328983743, 10467.895321046692, 10475.685729428129, 9077.636894524408, 9084.545772711206, 9089.909100908906, 9128.087191280818, 9238.630454781733, 18304.26782928721, 18422.355217567856, 18872.33946811855, 19202.8079719201, 19209.51976200606, 19357.612847743247, 19852.50368740793, 19854.00729963467, 20026.299079532146, 20030.39908802738, 12364.752704946031, 12457.252564846138, 12528.874711253135, 12533.122681412213, 12561.309127998047, 13742.312884355542, 13999.930000349934, 14067.366968486647, 14164.78752818688, 14176.716465670836, 11107.555697772323, 11321.660350189508, 11334.376609286568, 11374.31754094757, 11533.423328833693, 10737.624183153865, 10858.2399232053, 10869.184811139037, 11116.388598627153, 11181.776364472827, 9730.659426920023, 9907.900920990985, 9944.801103978025, 9985.0, 10010.14913732341, 7067.0, 7185.53294035886, 7278.417726581966, 7333.230010848784, 7342.48602597813, 7196.820079497845, 7298.562086274628, 7302.634868256672, 7356.0, 7410.62946852666], "value": 6760.5934025043325, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.7481421631704, 502.5075426082439, 528.4636094364221, 537.5726297593413, 538.3270911360798, 6367.235931688198, 6372.553921225516, 6376.0, 6444.226692796865, 6522.152120224364, 6111.266648002234, 6225.190725205717, 6232.59488133271, 6247.437730604256, 6340.812419715191, 5014.222795466709, 5015.648904576681, 5038.193888977764, 5041.798328066882, 5056.19100943849, 4782.282657601353, 4930.506949305077, 4977.925331120036, 4980.750962451881, 5000.97499512502, 4924.236743304794, 4991.126552853254, 5015.7898944675435, 5024.120778863702, 5051.3180720602795, 4968.527989840959, 4979.850604481835, 4985.725785081819, 5046.646734728571, 5067.214581739836, 4453.710508816916, 4484.358688757121, 4485.327200919855, 4499.0, 4530.86407407775, 6224.439800417984, 6278.340774218687, 6282.591631543981, 6308.2745484269435, 6326.335734747831, 5729.541636669056, 5783.826485205451, 5795.696453021779, 5798.942010579883, 5838.7080645967535, 6698.129243198402, 6776.559523630948, 6798.558093723892, 6850.6574671266735, 6853.4174595159475, 4847.5394837490385, 4861.513848615145, 4889.633277504166, 4902.950970490285, 4953.4798846121, 7031.558530501235, 7079.185893622196, 7098.325659062432, 7158.248383919666, 7175.748848790267, 5065.441103007322, 5105.157648987875, 5111.361079865017, 5116.232565115262, 5122.359705036871, 5111.437741848394, 5132.307138536325, 5137.8715532112, 5167.276581278624, 5168.010316028356, 5577.2191893135, 5628.380878103405, 5664.971675141598, 5717.2281741965135, 5788.913166302509, 6583.275839657634, 6681.465482761368, 6690.0, 6742.696578653972, 6808.3191680832515, 1634.3054201964117, 1676.6562854614774, 1680.9579760506085, 1743.2329774899003, 1755.9122043897753, 1144.8797876223043, 1187.8693343732182, 1217.7807994561065, 1230.993845030769, 1238.7027113492697, 5858.384869588717, 5952.255968003913, 5958.6333721447245, 5998.430149135826, 6056.909756243918, 6683.4987375947, 6756.729730810713, 6789.2871248518195, 6817.318268173242, 6832.419244364285, 5784.595078344475, 5828.562857785683, 5888.263967004124, 5977.970110149421, 6038.426349496791, 3572.2676851245433, 3587.5694916610173, 3638.5633723953297, 3669.541307336583, 3674.2259594006355, 6508.9023664645065, 6621.1392518972225, 6654.234763002317, 6656.966715166393, 6663.033860090357, 6971.745085884488, 7020.7542736004, 7045.436365090781, 7068.611226382598, 7110.857782844314, 6631.082323777522, 6637.701303441357, 6645.833854153685, 6657.866842663119, 6690.899636505554, 6800.353966373186, 6826.453883689193, 6839.897401538881, 6893.0694356261265, 6914.377706006484, 6711.697973591103, 6799.354061364163, 6800.421964133104, 6823.3601151217645, 6828.156397772673, 6638.900416493853, 6668.433183179484, 6676.399124078856, 6685.933140668531, 6752.864942701217, 4967.279744436964, 4983.127952608352, 5005.924911126263, 5039.571636410946, 5048.671836330626, 6887.931120688729, 6973.825654358681, 7012.7545535906, 7024.192217895007, 7069.611171385417, 6658.7336506539195, 6661.2006559213205, 6673.399394054559, 6677.165354330708, 6681.398674119353, 6597.416620011067, 6643.634600097041, 6679.966600166968, 6689.933100668931, 6762.256151823197, 6371.426571608577, 6516.6415847128865, 6550.279469258473, 6579.046038324321, 6585.901211481927, 6463.385978332051, 6538.215414150332, 6577.375149360803, 6624.840652885629, 6687.431568316748, 6409.436225509833, 6445.581037232565, 6456.838579035561, 6491.642959637076, 6495.317991611002], "value": 5131.243986926884, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [868.8696695495674, 903.4940433357319, 916.5234078279299, 919.83902817007, 923.6767131503974, 17747.290108395664, 18438.0, 18482.52139828814, 18484.907575462104, 18500.444986650404, 17014.82985170148, 17324.913375433138, 17395.652086958286, 17490.737638935425, 17566.648667026682, 999.090827347116, 1009.7980403919208, 1016.4307987526971, 1016.7661437869294, 1016.9288149829515, 1015.8273093574073, 1019.2202964731949, 1020.5305559442628, 1020.6478764826123, 1020.7601213714771, 1002.8846682631481, 1024.5082360466981, 1031.8555402243692, 1035.0994634667811, 1059.5496913811598, 1019.6991887393236, 1024.538957469138, 1025.4411345816548, 1025.7589466475372, 1029.953652085658, 756.4629113329543, 757.9431542634325, 758.5638258001616, 758.916519182895, 760.1334478694293, 17695.73456398155, 17696.91151544247, 17753.467395978012, 17770.46688599331, 17896.194671239824, 20851.478713032146, 21801.0, 22003.559928801333, 22076.11695532193, 22168.334949951364, 17074.48776536706, 17274.913625431916, 17356.56608584783, 17362.913185434118, 17439.651206975916, 3498.0904964709102, 3511.5083888255663, 3535.7878527288444, 3540.805255710935, 3543.539339885798, 131026.344868276, 131523.0, 135137.999999999, 136126.0, 137899.0, 14761.77857332141, 14891.553253402415, 14963.102213866985, 15198.088114713153, 15389.538313850602, 30259.848700756356, 31032.844835775675, 31350.52974205389, 31466.37067258642, 31501.52747708786, 8689.56552172389, 8713.607887645072, 8731.607077681392, 8773.034966153718, 8792.34057445694, 4186.28833098374, 4266.914661706811, 4272.957270427318, 4298.161858437637, 4319.43847299849, 4788.712677239377, 4948.826791062295, 4952.826651067196, 5087.313212716236, 5097.821576244888, 964.6961207219844, 990.2424645146468, 1130.4630300607146, 1174.7767924094394, 1177.0759953436511, 692.3042342445809, 700.0619170311807, 714.2571725405624, 714.4641518860855, 715.3847690985748, 11629.360385178896, 11757.412129393497, 11958.103142264192, 12727.0, 12987.935060324828, 660.6234446365526, 668.0647094068246, 676.82064252973, 677.5460441504204, 679.2121139478181, 5844.649321040752, 5925.247507503625, 5942.940570594239, 5967.194428752063, 5996.070609055603, 114136.0, 114252.0, 114272.0, 118891.0, 120157.39921300512, 97785.0, 99197.50401247948, 102856.0, 103129.0, 104790.0, 83595.0, 84944.57527712322, 87472.0, 88197.0, 88280.0, 11844.644660660195, 12743.362831858556, 12804.423800928795, 12838.871611283768, 13071.085024048605, 39413.408798868615, 39931.0, 40619.593804062766, 41453.79273103615, 41841.58158418377, 65483.0, 69813.30186698068, 70051.29948700448, 70603.64698176681, 73234.90147647896, 17651.823481765532, 17912.462626120716, 17929.910350448165, 17945.641087178443, 18041.1881465337, 19626.214951401784, 19781.40655780329, 19875.900620496806, 20137.597248054662, 20407.795922041183, 17071.40250091241, 17611.207495662984, 18033.0, 18184.27262909469, 18374.173162208, 17665.735013975056, 18057.819421805612, 18180.636387272443, 18716.438506844817, 19811.504712382306, 16817.831821682117, 16909.830901690824, 17447.738283926006, 17556.38552650651, 17579.29682812673, 11954.880451195724, 12319.0, 12646.494140234656, 12760.68098297513, 12976.221426714428, 13200.537981170613, 13902.860971390157, 13987.580372588838, 14057.22685252321, 14228.430862765374], "value": 9090.36139224673, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 117.0, 110.0, 111.0, 117.0, 109.0, 114.0, 105.0, 108.0, 114.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 122.0, 148.0, 180.0, 118.0, 147.0, 155.0, 120.0, 171.0, 122.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 171.0, 167.0, 164.0, 166.0, 164.0, 168.0, 165.0, 164.0, 163.0], "value": 166.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 74.0, 176.0, 74.0, 81.0, 83.0, 74.0, 161.0, 78.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 88.0, 127.0, 79.0, 86.0, 84.0, 132.0, 80.0, 89.0, 140.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 119.0, 122.0, 115.0, 115.0, 115.0, 115.0, 116.0, 114.0, 119.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 149.0, 156.0, 148.0, 152.0, 149.0, 148.0, 159.0, 148.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 101.0, 95.0, 95.0, 94.0, 98.0, 95.0, 94.0, 102.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 52.0, 52.0, 53.0, 55.0, 61.0, 53.0, 54.0, 52.0, 52.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 44.0, 38.0, 48.0, 41.0, 38.0, 38.0, 39.0, 41.0, 39.0], "value": 41.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 78.0, 85.0, 76.0, 76.0, 82.0, 76.0, 78.0, 74.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 120.0, 134.0, 119.0, 130.0, 120.0, 128.0, 123.0, 123.0, 170.0], "value": 129.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 176.0, 170.0, 172.0, 171.0, 173.0, 177.0, 174.0, 169.0, 175.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 68.0, 68.0, 65.0, 68.0, 80.0, 63.0, 67.0, 63.0], "value": 67.2, "unit": "score"}]}]} 03:05:18 INFO - Return code: 0 03:05:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:05:18 INFO - # TBPL SUCCESS # 03:05:18 INFO - Running post-action listener: _package_coverage_data 03:05:18 INFO - Running post-action listener: _resource_record_post_action 03:05:18 INFO - [mozharness: 2017-09-03 10:05:18.350853Z] Finished run-tests step (success) 03:05:18 INFO - Running post-run listener: _resource_record_post_run 03:05:18 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 14438400; Write bytes: 399364096; Read time: 8888; Write time: 357764 03:05:18 INFO - TinderboxPrint: CPU usage
13.3% 03:05:18 INFO - TinderboxPrint: I/O read bytes / time
14,438,400 / 8,888 03:05:18 INFO - TinderboxPrint: I/O write bytes / time
399,364,096 / 357,764 03:05:18 INFO - TinderboxPrint: CPU idle
8,284.3 (86.7%) 03:05:18 INFO - TinderboxPrint: CPU user
1,202.7 (12.6%) 03:05:18 INFO - TinderboxPrint: Swap in / out
0 / 0 03:05:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114618368; Read time: 0; Write time: 107500 03:05:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:05:18 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 12693504; Write bytes: 284311552; Read time: 8812; Write time: 250236 03:05:18 INFO - Running post-run listener: _upload_blobber_files 03:05:18 INFO - Blob upload gear active. 03:05:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:05:18 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 03:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:05:19 INFO - (blobuploader) - INFO - Open directory for files ... 03:05:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:05:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:05:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:26 INFO - (blobuploader) - INFO - Done attempting. 03:05:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:05:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:05:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:27 INFO - (blobuploader) - INFO - Done attempting. 03:05:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:05:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:05:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:28 INFO - (blobuploader) - INFO - Done attempting. 03:05:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:05:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:05:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:29 INFO - (blobuploader) - INFO - Done attempting. 03:05:29 INFO - (blobuploader) - INFO - Iteration through files over. 03:05:29 INFO - Return code: 0 03:05:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:05:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:05:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb6b7c2c83e8b93350fb6673a3a1ac326e3a08f86c909bbc12fbdf2b45ae86bfdf93fcceadfb792cad040fd859dafd2ff0a26fabc34feb2acffd32806e23296", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72e1dc66c0c05c949b290e4dc1987c24b0665f64582d6468afd0ea51e8f669f2f664196d01be9e94c66ae1bf98189248c27142cfbc35d8fc63e7d05993e1b27a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15d5b2353d487ac969a136f59c090f530e3a07b583f921d3a248aa1a522355e22d1e7044019abfb296831c369d709769e09a1f4365944df65b67b93ece66bc0e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7180e5a255c3fcf09766d0fae7fc8709f991ea6ccd07bf3a5b34ea666ba1bb4b616db3f137607ef6b625b292e3252376d8e30a0ad95aead599c298bf5840225"} 03:05:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:05:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:05:29 INFO - Contents: 03:05:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb6b7c2c83e8b93350fb6673a3a1ac326e3a08f86c909bbc12fbdf2b45ae86bfdf93fcceadfb792cad040fd859dafd2ff0a26fabc34feb2acffd32806e23296", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72e1dc66c0c05c949b290e4dc1987c24b0665f64582d6468afd0ea51e8f669f2f664196d01be9e94c66ae1bf98189248c27142cfbc35d8fc63e7d05993e1b27a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15d5b2353d487ac969a136f59c090f530e3a07b583f921d3a248aa1a522355e22d1e7044019abfb296831c369d709769e09a1f4365944df65b67b93ece66bc0e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7180e5a255c3fcf09766d0fae7fc8709f991ea6ccd07bf3a5b34ea666ba1bb4b616db3f137607ef6b625b292e3252376d8e30a0ad95aead599c298bf5840225"} 03:05:29 INFO - Running post-run listener: copy_logs_to_upload_dir 03:05:29 INFO - Copying logs to upload dir... 03:05:29 INFO - mkdir: /builds/slave/test/build/upload/logs 03:05:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.658687 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-09-03 03:05:29.709666) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 03:05:29.717676) ========= 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=dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb6b7c2c83e8b93350fb6673a3a1ac326e3a08f86c909bbc12fbdf2b45ae86bfdf93fcceadfb792cad040fd859dafd2ff0a26fabc34feb2acffd32806e23296", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72e1dc66c0c05c949b290e4dc1987c24b0665f64582d6468afd0ea51e8f669f2f664196d01be9e94c66ae1bf98189248c27142cfbc35d8fc63e7d05993e1b27a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15d5b2353d487ac969a136f59c090f530e3a07b583f921d3a248aa1a522355e22d1e7044019abfb296831c369d709769e09a1f4365944df65b67b93ece66bc0e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7180e5a255c3fcf09766d0fae7fc8709f991ea6ccd07bf3a5b34ea666ba1bb4b616db3f137607ef6b625b292e3252376d8e30a0ad95aead599c298bf5840225"} build_url:https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011176 build_url: 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb6b7c2c83e8b93350fb6673a3a1ac326e3a08f86c909bbc12fbdf2b45ae86bfdf93fcceadfb792cad040fd859dafd2ff0a26fabc34feb2acffd32806e23296", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72e1dc66c0c05c949b290e4dc1987c24b0665f64582d6468afd0ea51e8f669f2f664196d01be9e94c66ae1bf98189248c27142cfbc35d8fc63e7d05993e1b27a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15d5b2353d487ac969a136f59c090f530e3a07b583f921d3a248aa1a522355e22d1e7044019abfb296831c369d709769e09a1f4365944df65b67b93ece66bc0e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7180e5a255c3fcf09766d0fae7fc8709f991ea6ccd07bf3a5b34ea666ba1bb4b616db3f137607ef6b625b292e3252376d8e30a0ad95aead599c298bf5840225"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 03:05:29.749123) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 03:05:29.749459) ========= 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=dac658600622fd96716c9acf000001b3-1504426494.693453-1315144914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004410 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 03:05:29.830143) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 03:05:29.833464) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 03:05:29.833747) ========= ========= Total master_lag: 0.31 =========