builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-024 starttime: 1504431775.1 results: success (0) revision: 59db725def8282e1d77e83f002d247c7d0f95237 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 02:42:55.095947) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 02:42:55.096341) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 02:42:55.096658) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 02:42:55.117067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:42:55.117349) ========= 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=41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-03 02:42:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.8M=0s 2017-09-03 02:42:55 (35.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124752 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:42:55.267929) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:42:55.268249) ========= 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=41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018277 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:42:55.324737) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 9 secs) (at 2017-09-03 02:42:55.325039) ========= 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=41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-03 02:42:55,666 truncating revision to first 12 chars 2017-09-03 02:42:55,666 Setting DEBUG logging. 2017-09-03 02:42:55,666 attempt 1/10 2017-09-03 02:42:55,666 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:42:56,182 attempt 1/10 2017-09-03 02:42:56,716 current task status: no status available at this point. state: PENDING 2017-09-03 02:42:56,716 sleeping for 9.00s (attempt 1/10) 2017-09-03 02:43:05,726 attempt 2/10 2017-09-03 02:43:06,289 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:06,289 sleeping for 12.50s (attempt 2/10) 2017-09-03 02:43:18,802 attempt 3/10 2017-09-03 02:43:19,293 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:19,294 sleeping for 17.75s (attempt 3/10) 2017-09-03 02:43:37,062 attempt 4/10 2017-09-03 02:43:37,579 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:37,579 sleeping for 26.62s (attempt 4/10) 2017-09-03 02:44:04,231 attempt 5/10 2017-09-03 02:44:04,565 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-03 02:44:04,775 unpacking tar archive at: mozilla-central-59db725def82/testing/mozharness/ program finished with exit code 0 elapsedTime=69.506082 ========= master_lag: -0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 9 secs) (at 2017-09-03 02:44:04.708515) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:04.708847) ========= script_repo_revision: 59db725def8282e1d77e83f002d247c7d0f95237 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:04.709231) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:04.709490) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:04.722926) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-03 02:44:04.723234) ========= /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=41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:44:05 INFO - MultiFileLogger online at 20170903 02:44:05 in /builds/slave/test 02:44:05 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:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:44:05 INFO - {'append_to_log': False, 02:44:05 INFO - 'base_work_dir': '/builds/slave/test', 02:44:05 INFO - 'blob_upload_branch': 'Firefox', 02:44:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:44:05 INFO - 'branch': 'Firefox', 02:44:05 INFO - 'buildbot_json_path': 'buildprops.json', 02:44:05 INFO - 'code_coverage': False, 02:44:05 INFO - 'config_files': ('talos/linux_config.py',), 02:44:05 INFO - 'default_actions': ('clobber', 02:44:05 INFO - 'read-buildbot-config', 02:44:05 INFO - 'download-and-extract', 02:44:05 INFO - 'populate-webroot', 02:44:05 INFO - 'create-virtualenv', 02:44:05 INFO - 'install', 02:44:05 INFO - 'setup-mitmproxy', 02:44:05 INFO - 'run-tests'), 02:44:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:44:05 INFO - 'disable_ccov_upload': False, 02:44:05 INFO - 'disable_stylo': False, 02:44:05 INFO - 'download_minidump_stackwalk': True, 02:44:05 INFO - 'download_symbols': 'ondemand', 02:44:05 INFO - 'enable_stylo': False, 02:44:05 INFO - 'enable_webrender': False, 02:44:05 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:44:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:44:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:44:05 INFO - 'gecko_profile': False, 02:44:05 INFO - 'gecko_profile_interval': 0, 02:44:05 INFO - 'installer_path': 'installer.exe', 02:44:05 INFO - 'log_level': 'info', 02:44:05 INFO - 'log_name': 'talos', 02:44:05 INFO - 'log_to_console': True, 02:44:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:44:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:44:05 INFO - 'opt_config_files': (), 02:44:05 INFO - 'pip_index': False, 02:44:05 INFO - 'suite': 'dromaeojs-e10s', 02:44:05 INFO - 'system_bits': '32', 02:44:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:44:05 INFO - 'title': 'talos-linux64-ix-024', 02:44:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:44:05 INFO - 'use_talos_json': True, 02:44:05 INFO - 'verify': 'False', 02:44:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:44:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:44:05 INFO - 'work_dir': 'build'} 02:44:05 INFO - [mozharness: 2017-09-03 09:44:05.346447Z] Running clobber step. 02:44:05 INFO - Running pre-action listener: _resource_record_pre_action 02:44:05 INFO - Running main action method: clobber 02:44:05 INFO - rmtree: /builds/slave/test/build 02:44:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:44:06 INFO - Running post-action listener: _resource_record_post_action 02:44:06 INFO - [mozharness: 2017-09-03 09:44:06.895169Z] Finished clobber step (success) 02:44:06 INFO - [mozharness: 2017-09-03 09:44:06.895268Z] Running read-buildbot-config step. 02:44:06 INFO - Running pre-action listener: _resource_record_pre_action 02:44:06 INFO - Running main action method: read_buildbot_config 02:44:06 INFO - Using buildbot properties: 02:44:06 INFO - { 02:44:06 INFO - "project": "", 02:44:06 INFO - "product": "firefox", 02:44:06 INFO - "who": "archaeopteryx@coole-files.de", 02:44:06 INFO - "installer_path": "public/build/target.tar.bz2", 02:44:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 02:44:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:44:06 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 02:44:06 INFO - "stage_platform": "linux64-stylosequential", 02:44:06 INFO - "basedir": "/builds/slave/test", 02:44:06 INFO - "buildnumber": 56, 02:44:06 INFO - "platform": "ubuntu64_hw_styloseq", 02:44:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:44:06 INFO - "slavebuilddir": "test", 02:44:06 INFO - "taskId": "S__MOHM_QlOwNJa-sY__BQ", 02:44:06 INFO - "branch": "mozilla-central", 02:44:06 INFO - "script_repo_revision": "production", 02:44:06 INFO - "revision": "59db725def8282e1d77e83f002d247c7d0f95237", 02:44:06 INFO - "slavename": "talos-linux64-ix-024", 02:44:06 INFO - "repo_path": "mozilla-central" 02:44:06 INFO - } 02:44:06 INFO - Finding installer, test and symbols from parent task. 02:44:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S__MOHM_QlOwNJa-sY__BQ'}, attempt #1 02:44:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S__MOHM_QlOwNJa-sY__BQ'}, attempt #1 02:44:07 INFO - Task dependencies: JG8GD8vBTLSpKotpMpyhnw 02:44:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw'}, attempt #1 02:44:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:08 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json 02:44:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip 02:44:08 INFO - Running post-action listener: _resource_record_post_action 02:44:08 INFO - [mozharness: 2017-09-03 09:44:08.222022Z] Finished read-buildbot-config step (success) 02:44:08 INFO - [mozharness: 2017-09-03 09:44:08.222239Z] Running download-and-extract step. 02:44:08 INFO - Running pre-action listener: _resource_record_pre_action 02:44:08 INFO - Running main action method: download_and_extract 02:44:08 INFO - mkdir: /builds/slave/test/build/tests 02:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:08 INFO - trying https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json 02:44:08 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:08 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:08 INFO - Downloaded 989 bytes. 02:44:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:44:08 INFO - Using the following test package requirements: 02:44:08 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:44:08 INFO - u'common': [u'target.common.tests.zip'], 02:44:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:44:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:44:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:44:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:44:08 INFO - u'mozbase': [u'target.common.tests.zip'], 02:44:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:44:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:44:08 INFO - u'web-platform': [u'target.common.tests.zip', 02:44:08 INFO - u'target.web-platform.tests.tar.gz'], 02:44:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:44:08 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:44:08 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:08 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:08 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip into memory 02:44:09 INFO - Content-Length response header: 55022487 02:44:09 INFO - Bytes received: 55022487 02:44:20 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:44:20 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:20 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:20 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip into memory 02:44:21 INFO - Content-Length response header: 55022487 02:44:21 INFO - Bytes received: 55022487 02:44:33 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:33 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:33 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.talos.tests.zip into memory 02:44:35 INFO - Content-Length response header: 14496650 02:44:35 INFO - Bytes received: 14496650 02:44: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:44:35 INFO - trying https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:35 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:35 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:37 INFO - Downloaded 62661590 bytes. 02:44:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:37 INFO - mkdir: /builds/slave/test/properties 02:44:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:44:37 INFO - Writing to file /builds/slave/test/properties/build_url 02:44:37 INFO - Contents: 02:44:37 INFO - build_url:https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:37 INFO - Running post-action listener: _resource_record_post_action 02:44:37 INFO - Running post-action listener: find_tests_for_verification 02:44:37 INFO - Running post-action listener: set_extra_try_arguments 02:44:37 INFO - [mozharness: 2017-09-03 09:44:37.601165Z] Finished download-and-extract step (success) 02:44:37 INFO - [mozharness: 2017-09-03 09:44:37.601263Z] Running populate-webroot step. 02:44:37 INFO - Running pre-action listener: _resource_record_pre_action 02:44:37 INFO - Running main action method: populate_webroot 02:44:37 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:44:37 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:44:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:44:37 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:44:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:44:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:37 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:37 INFO - 'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:44:37 INFO - 'tests': ['damp', 'tps']}, 02:44:37 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:37 INFO - 'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['damp', 'tps']}, 02:44:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:44:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:44:37 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:44:37 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:44:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:37 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:37 INFO - 'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:37 INFO - 'other-e10s': {'tests': ['a11yr', 02:44:37 INFO - 'ts_paint', 02:44:37 INFO - 'tpaint', 02:44:37 INFO - 'sessionrestore', 02:44:37 INFO - 'sessionrestore_many_windows', 02:44:37 INFO - 'sessionrestore_no_auto_restore', 02:44:37 INFO - 'tabpaint']}, 02:44:37 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['a11yr', 02:44:37 INFO - 'ts_paint', 02:44:37 INFO - 'tpaint', 02:44:37 INFO - 'sessionrestore', 02:44:37 INFO - 'sessionrestore_many_windows', 02:44:37 INFO - 'sessionrestore_no_auto_restore', 02:44:37 INFO - 'tabpaint']}, 02:44:37 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:44:37 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:44:37 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['bloom_basic_singleton']}, 02:44:37 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['bloom_basic']}, 02:44:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:44:37 INFO - 'tsvgr_opacity', 02:44:37 INFO - 'tart', 02:44:37 INFO - 'tscrollx', 02:44:37 INFO - 'tsvg_static']}, 02:44:37 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['tsvgx', 02:44:37 INFO - 'tsvgr_opacity', 02:44:37 INFO - 'tart', 02:44:37 INFO - 'tscrollx', 02:44:37 INFO - 'tsvg_static']}, 02:44:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:44:37 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:37 INFO - 'talos_options': ['--enable-stylo'], 02:44:37 INFO - 'tests': ['tp5o']}, 02:44:37 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:37 INFO - 'talos_options': ['--mitmproxy', 02:44:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:37 INFO - '--firstNonBlankPaint'], 02:44:37 INFO - 'tests': ['tp6_google', 02:44:37 INFO - 'tp6_youtube', 02:44:37 INFO - 'tp6_amazon', 02:44:37 INFO - 'tp6_facebook']}, 02:44:37 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:37 INFO - 'talos_options': ['--enable-stylo', 02:44:37 INFO - '--mitmproxy', 02:44:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:37 INFO - '--firstNonBlankPaint'], 02:44:37 INFO - 'tests': ['tp6_google', 02:44:37 INFO - 'tp6_youtube', 02:44:37 INFO - 'tp6_amazon', 02:44:37 INFO - 'tp6_facebook']}, 02:44:37 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:37 INFO - 'talos_options': ['--enable-stylo', 02:44:37 INFO - '--stylo-threads=1', 02:44:37 INFO - '--mitmproxy', 02:44:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:37 INFO - '--firstNonBlankPaint'], 02:44:37 INFO - 'tests': ['tp6_google', 02:44:37 INFO - 'tp6_youtube', 02:44:37 INFO - 'tp6_amazon', 02:44:37 INFO - 'tp6_facebook']}, 02:44:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:44:37 INFO - 'talos_options': ['--xperf_path', 02:44:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:37 INFO - 'tests': ['tp5n']}, 02:44:37 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:37 INFO - 'talos_options': ['--enable-stylo', 02:44:37 INFO - '--xperf_path', 02:44:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:37 INFO - 'tests': ['tp5n']}}} 02:44:37 INFO - Running post-action listener: _resource_record_post_action 02:44:37 INFO - [mozharness: 2017-09-03 09:44:37.615430Z] Finished populate-webroot step (success) 02:44:37 INFO - [mozharness: 2017-09-03 09:44:37.615518Z] Running create-virtualenv step. 02:44:37 INFO - Running pre-action listener: _resource_record_pre_action 02:44:37 INFO - Running main action method: create_virtualenv 02:44:37 INFO - Creating virtualenv /builds/slave/test/build/venv 02:44:37 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:44:37 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:44:37 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:44:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:44:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:38 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:38 INFO - Installing pip...............done. 02:44:38 INFO - Return code: 0 02:44:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:44:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:44:38 INFO - Reading from file tmpfile_stdout 02:44:38 INFO - Output received: 02:44:38 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:44:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:38 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 0x12291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', '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:44:38 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:44:38 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:44:38 INFO - Using env: {'DISPLAY': ':0', 02:44:38 INFO - 'HOME': '/home/cltbld', 02:44:38 INFO - 'LANG': 'en_US.UTF-8', 02:44:38 INFO - 'LANGUAGE': 'en_US:en', 02:44:38 INFO - 'LOGNAME': 'cltbld', 02:44:38 INFO - 'MAIL': '/var/mail/cltbld', 02:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:38 INFO - 'MOZ_NO_REMOTE': '1', 02:44:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:38 INFO - 'NO_EM_RESTART': '1', 02:44:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:38 INFO - 'PWD': '/builds/slave/test', 02:44:38 INFO - 'SHELL': '/bin/bash', 02:44:38 INFO - 'SHLVL': '1', 02:44:38 INFO - 'TERM': 'linux', 02:44:38 INFO - 'TMOUT': '86400', 02:44:38 INFO - 'USER': 'cltbld', 02:44:38 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:38 INFO - '_': '/tools/buildbot/bin/python'} 02:44:39 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:44:39 INFO - Downloading/unpacking pip>=1.5 02:44:39 INFO - Running setup.py egg_info for package pip 02:44:39 INFO - warning: no files found matching 'pip/cacert.pem' 02:44:39 INFO - warning: no files found matching '*.html' under directory 'docs' 02:44:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:44:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:44:39 INFO - Installing collected packages: pip 02:44:39 INFO - Found existing installation: pip 1.1 02:44:39 INFO - Uninstalling pip: 02:44:39 INFO - Successfully uninstalled pip 02:44:39 INFO - Running setup.py install for pip 02:44:39 INFO - warning: no files found matching 'pip/cacert.pem' 02:44:39 INFO - warning: no files found matching '*.html' under directory 'docs' 02:44:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:44:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:44:39 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Successfully installed pip 02:44:39 INFO - Cleaning up... 02:44:39 INFO - Return code: 0 02:44:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:44:39 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:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:39 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:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:39 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 0x12291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', '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:44:39 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:44:39 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:44:39 INFO - Using env: {'DISPLAY': ':0', 02:44:39 INFO - 'HOME': '/home/cltbld', 02:44:39 INFO - 'LANG': 'en_US.UTF-8', 02:44:39 INFO - 'LANGUAGE': 'en_US:en', 02:44:39 INFO - 'LOGNAME': 'cltbld', 02:44:39 INFO - 'MAIL': '/var/mail/cltbld', 02:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:39 INFO - 'MOZ_NO_REMOTE': '1', 02:44:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:39 INFO - 'NO_EM_RESTART': '1', 02:44:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:39 INFO - 'PWD': '/builds/slave/test', 02:44:39 INFO - 'SHELL': '/bin/bash', 02:44:39 INFO - 'SHLVL': '1', 02:44:39 INFO - 'TERM': 'linux', 02:44:39 INFO - 'TMOUT': '86400', 02:44:39 INFO - 'USER': 'cltbld', 02:44:39 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:39 INFO - '_': '/tools/buildbot/bin/python'} 02:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:40 INFO - Downloading/unpacking psutil>=3.1.1 02:44:40 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:44:40 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:44:40 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:44:40 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:44:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:44:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:41 INFO - Installing collected packages: psutil 02:44:41 INFO - Running setup.py install for psutil 02:44:41 INFO - building 'psutil._psutil_linux' extension 02:44:41 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:44:41 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:44:41 INFO - building 'psutil._psutil_posix' extension 02:44:41 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:44:41 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:44:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:41 INFO - Successfully installed psutil 02:44:41 INFO - Cleaning up... 02:44:41 INFO - Return code: 0 02:44:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:44:41 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:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:41 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:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:41 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 0x12291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', '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:44:41 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:44:41 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:44:41 INFO - Using env: {'DISPLAY': ':0', 02:44:41 INFO - 'HOME': '/home/cltbld', 02:44:41 INFO - 'LANG': 'en_US.UTF-8', 02:44:41 INFO - 'LANGUAGE': 'en_US:en', 02:44:41 INFO - 'LOGNAME': 'cltbld', 02:44:41 INFO - 'MAIL': '/var/mail/cltbld', 02:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:41 INFO - 'MOZ_NO_REMOTE': '1', 02:44:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:41 INFO - 'NO_EM_RESTART': '1', 02:44:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:41 INFO - 'PWD': '/builds/slave/test', 02:44:41 INFO - 'SHELL': '/bin/bash', 02:44:41 INFO - 'SHLVL': '1', 02:44:41 INFO - 'TERM': 'linux', 02:44:41 INFO - 'TMOUT': '86400', 02:44:41 INFO - 'USER': 'cltbld', 02:44:41 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:41 INFO - '_': '/tools/buildbot/bin/python'} 02:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:44:41 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:44:41 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:44:41 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:44:41 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:44:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:44:43 INFO - Installing collected packages: mozsystemmonitor 02:44:43 INFO - Running setup.py install for mozsystemmonitor 02:44:43 INFO - Successfully installed mozsystemmonitor 02:44:43 INFO - Cleaning up... 02:44:43 INFO - Return code: 0 02:44:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:43 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 0x12291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', '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:44:43 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:44:43 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:44:43 INFO - Using env: {'DISPLAY': ':0', 02:44:43 INFO - 'HOME': '/home/cltbld', 02:44:43 INFO - 'LANG': 'en_US.UTF-8', 02:44:43 INFO - 'LANGUAGE': 'en_US:en', 02:44:43 INFO - 'LOGNAME': 'cltbld', 02:44:43 INFO - 'MAIL': '/var/mail/cltbld', 02:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:43 INFO - 'MOZ_NO_REMOTE': '1', 02:44:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:43 INFO - 'NO_EM_RESTART': '1', 02:44:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:43 INFO - 'PWD': '/builds/slave/test', 02:44:43 INFO - 'SHELL': '/bin/bash', 02:44:43 INFO - 'SHLVL': '1', 02:44:43 INFO - 'TERM': 'linux', 02:44:43 INFO - 'TMOUT': '86400', 02:44:43 INFO - 'USER': 'cltbld', 02:44:43 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:43 INFO - '_': '/tools/buildbot/bin/python'} 02:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:43 INFO - Downloading/unpacking jsonschema==2.5.1 02:44:43 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:44:43 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:44:43 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:44:43 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:44:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:44:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:44:44 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:44:44 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:44:44 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:44:44 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:44:44 INFO - Downloading functools32-3.2.3-2.tar.gz 02:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:44:44 INFO - warning: no files found matching '*.txt' 02:44:44 INFO - no previously-included directories found matching 'build' 02:44:44 INFO - no previously-included directories found matching 'dist' 02:44:44 INFO - no previously-included directories found matching '.git*' 02:44:44 INFO - Installing collected packages: jsonschema, functools32 02:44:44 INFO - Running setup.py install for functools32 02:44:44 INFO - warning: no files found matching '*.txt' 02:44:44 INFO - no previously-included directories found matching 'build' 02:44:44 INFO - no previously-included directories found matching 'dist' 02:44:44 INFO - no previously-included directories found matching '.git*' 02:44:44 INFO - Successfully installed jsonschema functools32 02:44:44 INFO - Cleaning up... 02:44:44 INFO - Return code: 0 02:44:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:44:44 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:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:44 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:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:45 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 0x12291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', '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:44:45 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:44:45 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:44:45 INFO - Using env: {'DISPLAY': ':0', 02:44:45 INFO - 'HOME': '/home/cltbld', 02:44:45 INFO - 'LANG': 'en_US.UTF-8', 02:44:45 INFO - 'LANGUAGE': 'en_US:en', 02:44:45 INFO - 'LOGNAME': 'cltbld', 02:44:45 INFO - 'MAIL': '/var/mail/cltbld', 02:44:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:45 INFO - 'MOZ_NO_REMOTE': '1', 02:44:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:45 INFO - 'NO_EM_RESTART': '1', 02:44:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:45 INFO - 'PWD': '/builds/slave/test', 02:44:45 INFO - 'SHELL': '/bin/bash', 02:44:45 INFO - 'SHLVL': '1', 02:44:45 INFO - 'TERM': 'linux', 02:44:45 INFO - 'TMOUT': '86400', 02:44:45 INFO - 'USER': 'cltbld', 02:44:45 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:44:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:45 INFO - '_': '/tools/buildbot/bin/python'} 02:44:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:44:45 INFO - Cleaning up... 02:44:45 INFO - Return code: 0 02:44:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:44:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:45 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 0x12291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', '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:44:45 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:44:45 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:44:45 INFO - Using env: {'DISPLAY': ':0', 02:44:45 INFO - 'HOME': '/home/cltbld', 02:44:45 INFO - 'LANG': 'en_US.UTF-8', 02:44:45 INFO - 'LANGUAGE': 'en_US:en', 02:44:45 INFO - 'LOGNAME': 'cltbld', 02:44:45 INFO - 'MAIL': '/var/mail/cltbld', 02:44:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:45 INFO - 'MOZ_NO_REMOTE': '1', 02:44:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:45 INFO - 'NO_EM_RESTART': '1', 02:44:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:45 INFO - 'PWD': '/builds/slave/test', 02:44:45 INFO - 'SHELL': '/bin/bash', 02:44:45 INFO - 'SHLVL': '1', 02:44:45 INFO - 'TERM': 'linux', 02:44:45 INFO - 'TMOUT': '86400', 02:44:45 INFO - 'USER': 'cltbld', 02:44:45 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:44:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:45 INFO - '_': '/tools/buildbot/bin/python'} 02:44:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:45 INFO - Downloading/unpacking blobuploader==1.2.4 02:44:45 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:44:45 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:44:45 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:44:45 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:44:46 INFO - Downloading blobuploader-1.2.4.tar.gz 02:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:44:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:44:46 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:44:46 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:44:46 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:44:46 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:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:44:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:44:46 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:44:46 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:44:46 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:44:46 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:44:47 INFO - Downloading docopt-0.6.1.tar.gz 02:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:44:47 INFO - Installing collected packages: blobuploader, requests, docopt 02:44:47 INFO - Running setup.py install for blobuploader 02:44:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:44:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:44:47 INFO - Running setup.py install for requests 02:44:47 INFO - Running setup.py install for docopt 02:44:47 INFO - Successfully installed blobuploader requests docopt 02:44:47 INFO - Cleaning up... 02:44:47 INFO - Return code: 0 02:44:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:47 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:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:47 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:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:47 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 0x12291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', '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:44:47 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:44:47 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:44:47 INFO - Using env: {'DISPLAY': ':0', 02:44:47 INFO - 'HOME': '/home/cltbld', 02:44:47 INFO - 'LANG': 'en_US.UTF-8', 02:44:47 INFO - 'LANGUAGE': 'en_US:en', 02:44:47 INFO - 'LOGNAME': 'cltbld', 02:44:47 INFO - 'MAIL': '/var/mail/cltbld', 02:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:47 INFO - 'MOZ_NO_REMOTE': '1', 02:44:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:47 INFO - 'NO_EM_RESTART': '1', 02:44:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:47 INFO - 'PWD': '/builds/slave/test', 02:44:47 INFO - 'SHELL': '/bin/bash', 02:44:47 INFO - 'SHLVL': '1', 02:44:47 INFO - 'TERM': 'linux', 02:44:47 INFO - 'TMOUT': '86400', 02:44:47 INFO - 'USER': 'cltbld', 02:44:47 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:47 INFO - '_': '/tools/buildbot/bin/python'} 02:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:47 INFO - Running setup.py (path:/tmp/pip-cbQfD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:48 INFO - Running setup.py (path:/tmp/pip-m8OiIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:48 INFO - Running setup.py (path:/tmp/pip-UWVCUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:48 INFO - Running setup.py (path:/tmp/pip-JhGfny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:48 INFO - Running setup.py (path:/tmp/pip-pItNqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:48 INFO - Running setup.py (path:/tmp/pip-1ToPt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:48 INFO - Running setup.py (path:/tmp/pip-bVuWUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:48 INFO - Running setup.py (path:/tmp/pip-DWLTIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:48 INFO - Running setup.py (path:/tmp/pip-P5DuSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:48 INFO - Running setup.py (path:/tmp/pip-bR4fRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:48 INFO - Running setup.py (path:/tmp/pip-sAL54P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:48 INFO - Running setup.py (path:/tmp/pip-dmmrjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:48 INFO - Running setup.py (path:/tmp/pip-QdN2Vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:48 INFO - Running setup.py (path:/tmp/pip-xyXqHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:49 INFO - Running setup.py (path:/tmp/pip-uEyGUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:49 INFO - Running setup.py (path:/tmp/pip-5BrCna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:49 INFO - Running setup.py (path:/tmp/pip-6atWRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:44:49 INFO - Running setup.py install for manifestparser 02:44:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for mozcrash 02:44:49 INFO - Running setup.py install for mozdebug 02:44:49 INFO - Running setup.py install for mozdevice 02:44:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for mozfile 02:44:49 INFO - Running setup.py install for mozhttpd 02:44:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for mozinfo 02:44:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for mozInstall 02:44:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:44:50 INFO - Running setup.py install for mozleak 02:44:50 INFO - Running setup.py install for mozlog 02:44:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:44:50 INFO - Running setup.py install for moznetwork 02:44:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:44:50 INFO - Running setup.py install for mozprocess 02:44:50 INFO - Running setup.py install for mozprofile 02:44:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:44:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:44:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:44:50 INFO - Running setup.py install for mozrunner 02:44:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:44:50 INFO - Running setup.py install for mozscreenshot 02:44:50 INFO - Running setup.py install for moztest 02:44:50 INFO - Running setup.py install for mozversion 02:44:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:44:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:44:50 INFO - Cleaning up... 02:44:50 INFO - Return code: 0 02:44:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:50 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:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:50 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:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:50 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 0x12291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', '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:44:50 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:44:50 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:44:50 INFO - Using env: {'DISPLAY': ':0', 02:44:50 INFO - 'HOME': '/home/cltbld', 02:44:50 INFO - 'LANG': 'en_US.UTF-8', 02:44:50 INFO - 'LANGUAGE': 'en_US:en', 02:44:50 INFO - 'LOGNAME': 'cltbld', 02:44:50 INFO - 'MAIL': '/var/mail/cltbld', 02:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:50 INFO - 'MOZ_NO_REMOTE': '1', 02:44:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:50 INFO - 'NO_EM_RESTART': '1', 02:44:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:50 INFO - 'PWD': '/builds/slave/test', 02:44:50 INFO - 'SHELL': '/bin/bash', 02:44:50 INFO - 'SHLVL': '1', 02:44:50 INFO - 'TERM': 'linux', 02:44:50 INFO - 'TMOUT': '86400', 02:44:50 INFO - 'USER': 'cltbld', 02:44:50 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:50 INFO - '_': '/tools/buildbot/bin/python'} 02:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:51 INFO - Running setup.py (path:/tmp/pip-dGKkT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:51 INFO - Running setup.py (path:/tmp/pip-nhN2VE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:51 INFO - Running setup.py (path:/tmp/pip-HMndDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:51 INFO - Running setup.py (path:/tmp/pip-XxT6LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:51 INFO - Running setup.py (path:/tmp/pip-8EpUZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:51 INFO - Running setup.py (path:/tmp/pip-4EPAOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:51 INFO - Running setup.py (path:/tmp/pip-VIZeOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:51 INFO - Running setup.py (path:/tmp/pip-1vQ97e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:51 INFO - Running setup.py (path:/tmp/pip-XFnOgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:51 INFO - Running setup.py (path:/tmp/pip-yWm7W7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:51 INFO - Running setup.py (path:/tmp/pip-Y0tEbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:51 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:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:51 INFO - Running setup.py (path:/tmp/pip-V3veSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:52 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:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:52 INFO - Running setup.py (path:/tmp/pip-6XgK69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:52 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:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:52 INFO - Running setup.py (path:/tmp/pip-g15xWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:52 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:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:52 INFO - Running setup.py (path:/tmp/pip-ZYORbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:52 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:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:52 INFO - Running setup.py (path:/tmp/pip-g4WSb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:52 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:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:52 INFO - Running setup.py (path:/tmp/pip-6X_0lC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:52 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:44:52 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:44:52 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:44:52 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:44:52 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:44:52 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:44:52 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:44:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:44:52 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:44:52 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:44:52 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:44:52 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:44:53 INFO - Downloading blessings-1.6.tar.gz 02:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:44:53 INFO - Installing collected packages: blessings 02:44:53 INFO - Running setup.py install for blessings 02:44:53 INFO - Successfully installed blessings 02:44:53 INFO - Cleaning up... 02:44:53 INFO - Return code: 0 02:44:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:44:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:53 INFO - Reading from file tmpfile_stdout 02:44:53 INFO - Current package versions: 02:44:53 INFO - argparse == 1.2.1 02:44:53 INFO - blessings == 1.6 02:44:53 INFO - blobuploader == 1.2.4 02:44:53 INFO - docopt == 0.6.1 02:44:53 INFO - functools32 == 3.2.3-2 02:44:53 INFO - jsonschema == 2.5.1 02:44:53 INFO - manifestparser == 1.1 02:44:53 INFO - mozInstall == 1.13 02:44:53 INFO - mozcrash == 1.0 02:44:53 INFO - mozdebug == 0.1 02:44:53 INFO - mozdevice == 0.51 02:44:53 INFO - mozfile == 1.2 02:44:53 INFO - mozhttpd == 0.7 02:44:53 INFO - mozinfo == 0.10 02:44:53 INFO - mozleak == 0.1 02:44:53 INFO - mozlog == 3.5 02:44:53 INFO - moznetwork == 0.27 02:44:53 INFO - mozprocess == 0.25 02:44:53 INFO - mozprofile == 0.28 02:44:53 INFO - mozrunner == 6.13 02:44:53 INFO - mozscreenshot == 0.1 02:44:53 INFO - mozsystemmonitor == 0.3 02:44:53 INFO - moztest == 0.8 02:44:53 INFO - mozversion == 1.4 02:44:53 INFO - psutil == 3.1.1 02:44:53 INFO - requests == 1.2.3 02:44:53 INFO - wsgiref == 0.1.2 02:44:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:53 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:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:53 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:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:53 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 0x12291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', '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:44:53 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:44:53 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:44:53 INFO - Using env: {'DISPLAY': ':0', 02:44:53 INFO - 'HOME': '/home/cltbld', 02:44:53 INFO - 'LANG': 'en_US.UTF-8', 02:44:53 INFO - 'LANGUAGE': 'en_US:en', 02:44:53 INFO - 'LOGNAME': 'cltbld', 02:44:53 INFO - 'MAIL': '/var/mail/cltbld', 02:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:53 INFO - 'MOZ_NO_REMOTE': '1', 02:44:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:53 INFO - 'NO_EM_RESTART': '1', 02:44:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:53 INFO - 'PWD': '/builds/slave/test', 02:44:53 INFO - 'SHELL': '/bin/bash', 02:44:53 INFO - 'SHLVL': '1', 02:44:53 INFO - 'TERM': 'linux', 02:44:53 INFO - 'TMOUT': '86400', 02:44:53 INFO - 'USER': 'cltbld', 02:44:53 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:53 INFO - '_': '/tools/buildbot/bin/python'} 02:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:54 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:44:54 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:44:54 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:44:54 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:44:54 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:44:54 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:44:54 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:44:54 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:44:54 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:44:54 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:44:54 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:44:54 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:44:54 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:44:54 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:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:44:55 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:44:55 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:44:55 INFO - Installing collected packages: simplejson 02:44:55 INFO - Running setup.py install for simplejson 02:44:55 INFO - building 'simplejson._speedups' extension 02:44:55 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:44:56 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:44:56 INFO - Successfully installed simplejson 02:44:56 INFO - Cleaning up... 02:44:56 INFO - Return code: 0 02:44:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:56 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 0x12291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', '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:44:56 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:44:56 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:44:56 INFO - Using env: {'DISPLAY': ':0', 02:44:56 INFO - 'HOME': '/home/cltbld', 02:44:56 INFO - 'LANG': 'en_US.UTF-8', 02:44:56 INFO - 'LANGUAGE': 'en_US:en', 02:44:56 INFO - 'LOGNAME': 'cltbld', 02:44:56 INFO - 'MAIL': '/var/mail/cltbld', 02:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:56 INFO - 'MOZ_NO_REMOTE': '1', 02:44:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:56 INFO - 'NO_EM_RESTART': '1', 02:44:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:56 INFO - 'PWD': '/builds/slave/test', 02:44:56 INFO - 'SHELL': '/bin/bash', 02:44:56 INFO - 'SHLVL': '1', 02:44:56 INFO - 'TERM': 'linux', 02:44:56 INFO - 'TMOUT': '86400', 02:44:56 INFO - 'USER': 'cltbld', 02:44:56 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:56 INFO - '_': '/tools/buildbot/bin/python'} 02:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:44:56 INFO - Cleaning up... 02:44:56 INFO - Return code: 0 02:44:56 INFO - Running post-action listener: _resource_record_post_action 02:44:56 INFO - Running post-action listener: _start_resource_monitoring 02:44:56 INFO - Starting resource monitoring. 02:44:56 INFO - [mozharness: 2017-09-03 09:44:56.642451Z] Finished create-virtualenv step (success) 02:44:56 INFO - [mozharness: 2017-09-03 09:44:56.642918Z] Running install step. 02:44:56 INFO - Running pre-action listener: _resource_record_pre_action 02:44:56 INFO - Running main action method: install 02:44:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:56 INFO - Reading from file tmpfile_stdout 02:44:56 INFO - Detecting whether we're running mozinstall >=1.0... 02:44:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:44:57 INFO - Reading from file tmpfile_stdout 02:44:57 INFO - Output received: 02:44:57 INFO - Usage: mozinstall [options] installer 02:44:57 INFO - Options: 02:44:57 INFO - -h, --help show this help message and exit 02:44:57 INFO - -d DEST, --destination=DEST 02:44:57 INFO - Directory to install application into. [default: 02:44:57 INFO - "/builds/slave/test"] 02:44:57 INFO - --app=APP Application being installed. [default: firefox] 02:44:57 INFO - mkdir: /builds/slave/test/build/application 02:44:57 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:44:57 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:13 INFO - Reading from file tmpfile_stdout 02:45:13 INFO - Output received: 02:45:13 INFO - /builds/slave/test/build/application/firefox/firefox 02:45:13 INFO - Running post-action listener: _resource_record_post_action 02:45:13 INFO - [mozharness: 2017-09-03 09:45:13.527831Z] Finished install step (success) 02:45:13 INFO - [mozharness: 2017-09-03 09:45:13.528083Z] Running setup-mitmproxy step. 02:45:13 INFO - Running pre-action listener: _resource_record_pre_action 02:45:13 INFO - Running main action method: setup_mitmproxy 02:45:13 INFO - Skipping: mitmproxy is not required 02:45:13 INFO - Running post-action listener: _resource_record_post_action 02:45:13 INFO - [mozharness: 2017-09-03 09:45:13.529304Z] Finished setup-mitmproxy step (success) 02:45:13 INFO - [mozharness: 2017-09-03 09:45:13.529526Z] Running run-tests step. 02:45:13 INFO - Running pre-action listener: _resource_record_pre_action 02:45:13 INFO - Running pre-action listener: _set_gcov_prefix 02:45:13 INFO - Running main action method: run_tests 02:45:13 WARNING - Try message not found. 02:45:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:13 INFO - Python 2.7.3 02:45:13 INFO - Return code: 0 02:45:13 INFO - grabbing minidump binary from tooltool 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 - 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 0x1599090>, '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 0x1599d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1593f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:13 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:13 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:13 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:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:13 INFO - Return code: 0 02:45:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:45:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:13 INFO - ENV: RUST_BACKTRACE is now full 02:45:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:45:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:13 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-024', '--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:13 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-024 --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: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 - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:13 INFO - 'MOZ_NO_REMOTE': '1', 02:45:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 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': '/builds/slave/test/build/venv/bin:/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 - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:13 INFO - 'RUST_BACKTRACE': 'full', 02:45:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:13 INFO - 'SHELL': '/bin/bash', 02:45:13 INFO - 'SHLVL': '1', 02:45:13 INFO - 'STYLO_FORCE_ENABLED': '1', 02:45:13 INFO - 'STYLO_THREADS': '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': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:13 INFO - '_': '/tools/buildbot/bin/python'} 02:45:13 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-024', '--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:13 INFO - mozversion application_buildid: 20170903085316 02:45:13 INFO - mozversion application_changeset: 59db725def8282e1d77e83f002d247c7d0f95237 02:45:13 INFO - mozversion application_display_name: Nightly 02:45:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:13 INFO - mozversion application_name: Firefox 02:45:13 INFO - mozversion application_remotingname: firefox 02:45:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:45:13 INFO - mozversion application_vendor: Mozilla 02:45:13 INFO - mozversion application_version: 57.0a1 02:45:13 INFO - mozversion platform_buildid: 20170903085316 02:45:13 INFO - mozversion platform_changeset: 59db725def8282e1d77e83f002d247c7d0f95237 02:45:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:45:13 INFO - mozversion platform_version: 57.0a1 02:45:13 INFO - using testdate: 1504431913 02:45:13 INFO - actual date: 1504431913 02:45:13 INFO - starting webserver on 'localhost:54292' 02:45:13 INFO - SUITE-START | Running 2 tests 02:45:13 INFO - TEST-START | dromaeo_css 02:45:13 INFO - Initialising browser for dromaeo_css test... 02:45:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54292/getInfo.html -profile /tmp/tmpDki_SG/profile 02:45:13 INFO - TEST-INFO | started process 16913 (/builds/slave/test/build/application/firefox/firefox http://localhost:54292/getInfo.html) 02:45:19 INFO - TEST-INFO | 16913: exit 0 02:45:19 INFO - Browser initialized. 02:45:19 INFO - Running cycle 1/1 for dromaeo_css test... 02:45:19 INFO - Using env: {'DISPLAY': ':0', 02:45:19 INFO - 'HOME': '/home/cltbld', 02:45:19 INFO - 'JSGC_DISABLE_POISONING': '1', 02:45:19 INFO - 'LANG': 'en_US.UTF-8', 02:45:19 INFO - 'LANGUAGE': 'en_US:en', 02:45:19 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:45:19 INFO - 'LOGNAME': 'cltbld', 02:45:19 INFO - 'MAIL': '/var/mail/cltbld', 02:45:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:19 INFO - 'MOZ_CRASHREPORTER': '1', 02:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:19 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:45:19 INFO - 'MOZ_NO_REMOTE': '1', 02:45:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:19 INFO - 'NO_EM_RESTART': '1', 02:45:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:19 INFO - 'PWD': '/builds/slave/test', 02:45:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:19 INFO - 'RUST_BACKTRACE': 'full', 02:45:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:19 INFO - 'SHELL': '/bin/bash', 02:45:19 INFO - 'SHLVL': '1', 02:45:19 INFO - 'STYLO_FORCE_ENABLED': '1', 02:45:19 INFO - 'STYLO_THREADS': '1', 02:45:19 INFO - 'TERM': 'linux', 02:45:19 INFO - 'TMOUT': '86400', 02:45:19 INFO - 'USER': 'cltbld', 02:45:19 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 02:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:19 INFO - '_': '/tools/buildbot/bin/python'} 02:45:19 INFO - TEST-INFO | started process 17096 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDki_SG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:20 INFO - PID 17096 | 02:45:20 INFO - PID 17096 | (/builds/slave/test/build/application/firefox/firefox:17148): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:45:20 INFO - PID 17096 | 02:45:30 INFO - PID 17096 | RSS: Main: 172265472 02:45:30 INFO - PID 17096 | 02:48:30 INFO - PID 17096 | Cycle 1(1): loaded http://localhost:54292/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54292/tests/dromaeo/cssquery-ext.html) 02:48:31 INFO - PID 17096 | RSS: Main: 171933696 02:48:31 INFO - PID 17096 | 02:51:32 INFO - PID 17096 | Cycle 1(1): loaded http://localhost:54292/tests/dromaeo/cssquery-ext.html (next: http://localhost:54292/tests/dromaeo/cssquery-jquery.html) 02:51:33 INFO - PID 17096 | RSS: Main: 170364928 02:51:33 INFO - PID 17096 | 02:54:35 INFO - PID 17096 | Cycle 1(1): loaded http://localhost:54292/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54292/tests/dromaeo/cssquery-mootools.html) 02:54:35 INFO - PID 17096 | RSS: Main: 172761088 02:54:35 INFO - PID 17096 | 02:57:37 INFO - PID 17096 | Cycle 1(1): loaded http://localhost:54292/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54292/tests/dromaeo/cssquery-prototype.html) 02:57:38 INFO - PID 17096 | RSS: Main: 169902080 02:57:38 INFO - PID 17096 | 03:00:41 INFO - PID 17096 | Cycle 1(1): loaded http://localhost:54292/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54292/tests/dromaeo/cssquery-yui.html) 03:00:42 INFO - PID 17096 | RSS: Main: 172544000 03:00:42 INFO - PID 17096 | 03:03:43 INFO - PID 17096 | Cycle 1(1): loaded http://localhost:54292/tests/dromaeo/cssquery-yui.html (next: http://localhost:54292/tests/dromaeo/cssquery-dojo.html) 03:03:44 INFO - PID 17096 | RSS: Main: 175128576 03:03:44 INFO - PID 17096 | 03:03:44 INFO - PID 17096 | __start_tp_report 03:03:44 INFO - PID 17096 | _x_x_mozilla_page_load 03:03:44 INFO - PID 17096 | _x_x_mozilla_page_load_details 03:03:44 INFO - PID 17096 | |i|pagename|runs| 03:03:44 INFO - PID 17096 | |0;dojo.html;3325.002499250226;3337.9165520861975;3340.2818394045275;3381.6618338166204;3385.390629686657;6392.6484043377595;6468.32082631324;6488.870222595545;6495.8051258462265;6523.673816309183;6338.144350512679;6431.38901804329;6436.388543088399;6437.388448097423;6448.0327950807405;5859.296884373881;5868.823935281948;5882.058870580708;5921.763129474837;5927.436893495111;5908;5909.20225769522;5926.170336152942;6017.277926648809;6048.092786082079;5880.3825598312205;5956.851078723023;5959.791407300745;5977.701114944235;6017.759289628431;5760.798372057;5822.3886491918165;5847.912281315784;5915.349311575723;5918.348981612019;5731.340895796995;5870.501007414332;5889.676067816268;5973.6415815050805;6012.549058820606;6357.300696923335;6373.298937116913;6384.042393640944;6433.035044743326;6468;6725.394714475672;6744.258131605519;6779.796606101825;6794.592324460499;6821.147356580427;6886.48351373644;6896.551724137915;6904.620245886475;6923.134608173978;6940.583564986117;6375.075614035985;6377.808665740035;6378.489720822323;6458.2895881453005;6466.74133034678;7136.857262854714;7156.92843071573;7208.639568021578;7226.0606121204955;7240.420766338681;6797.9660101700165;6801.591904485647;6810.250872404032;6897.1033765611155;6932.064171336805;6721.764738234138;6769.323067693192;6961.582305061713;7015.193252775894;7056.15326160864;6777.627230502271;6786.76246331376;6802.97955306708;6830.385265326145;6859.279775623588;6910;6912.343327383891;6914.792556223321;6947.131608548932;6951;5387.865303367369;5457.617966742369;5468.4531546845;5479.369872464717;5488.286522752017;4655.837045703384;4657.5575320344515;4754.358161648133;4782.760861956958;4796.832110876103;5960.6721630309885;5966.134910437962;5971.104334349882;5979.521638269015;6038.245219347582;6848.280930502325;6935.063766391472;6956.130483689539;6991.615461149686;7015.719371225196;6027.758889644365;6070.3929607038945;6083.908741368883;6091.664958427329;6101.725422356005;5918.881622367615;5920.970395148083;5979.402059794073;6007.008843540767;6013.278406591149;6694.799156025327;6703.22912865017;6764.458843292525;6776.085228494188;6780.932190678129;6832.487563432663;6892.551984121016;6913;6920.446364290744;6922.550034247758;6540.476761859135;6568.770093046721;6609.636469994197;6663.36698013688;6750.088738020305;6883.793486195422;6901.482388820959;6916.23921368639;6937.514373993772;6955.1653801544135;6970.65146742671;6987.475939304674;7063.894041589278;7066.364027237573;7074.787756367317;6612.669366531751;6674.265830758515;6686.933130668631;6767.86464270702;6774.356436138559;6423.197100362455;6492.870142597028;6503.057056726842;6508.511861610493;6796.558223715443;6216.409441103088;6507.707153178024;6533.118029066015;6566.409023187936;6568.146141001639;6727.966360168168;6763.458923286027;6768.492363072691;6786.049953006682;6806.7957961261245;6263.3736626337;6366.395192456709;6368.42684158428;6485.092087107903;6567.737290508326 03:03:44 INFO - PID 17096 | |1;ext.html;777.6772639354665;840.6595328891799;853.3599800149888;853.7012045783972;1124.3254047571454;29689.40621187575;30142.397152056947;39101;39561.99999999993;39676.40485392721;40505.392419113734;40814.591854081526;40894.38658420126;40903.38644920329;41184.58815411845;7189.928100718978;7583.355414789736;7788.766337009871;7792.610369481532;7803.258690424401;7578.0906291244855;7690.884636730453;7705.653245603933;7707;7707.344875685573;7686.5772382518935;7727.381809455258;7743.728969486069;7747.922520774805;7754.573498457583;7659.846803063935;7662.080550333968;7684.615769211545;7688.038995125609;7695.268949449794;7631.923680763232;7672.539647621104;7686.654100565488;7687.0391201099865;7695.4997925134685;36382.81808590967;36604.26791464182;36627.4505882412;36628.267434651425;36664;3454.1709989602564;3479.025872755607;3488.197714525648;3496.021114088059;3496.6503349665086;30998.535021974687;31026;31055.84472077625;31062.0681379559;31115.688843111504;4887.511248875119;5048.7728052237735;5071.670341427833;5080.110980578384;5093.770780314858;192749;193328;194321;194458.02770986196;194803;46509;46512.3023154653;46631.533684663395;46668.5333146671;46679.29981050287;48068;48163.759181203866;48220.5177948216;48232.27651585229;48240.758796205795;12355.196912200858;15238.923805381124;15481.845181548266;15488.5353439397;15508.767368489256;9712.562934667956;12185.756284874431;12262.570810021607;12306.015518758479;12320.938395307967;13417.463301468028;13443.126196797177;13446.529371471952;13508.594742157751;13548.322583870768;5258.211268309784;5443.129099344165;5461.2354270402175;5517.5585953123655;5595.860103497445;967.6468089147551;979.299800642544;980.5391466011106;981.2101258486987;996.1980605612551;21841.890790545945;22067.448313792283;28574.571381429294;28665;28860;1048.6172547020283;1052.4053909541085;1067.1089640150406;1081.6376513867785;1090.9454527273604;11832.112591555668;11863.703407414883;11963.581274655346;12011.219270747373;12111.939440302742;246646;249042;249535;249834;250376;48859;48962.26556601654;49113;49144;49178.75410622924;1406.6413064668443;1423.309694798022;1429.4996751137019;1431.434583339575;1439.2803598200899;21713.782862171178;21908;21964.780352196914;22086.447838804157;22474.438139046655;34471;34687.13282167966;34713.65286347104;34735.652643474255;34749.82625086858;209001;209546;209562;211087;211420;31085.22286942754;31965.20086997844;31967.520487192247;32359.191020224684;32370.35259294848;40374.39438408367;40423;40544;40913.795431022656;40984.59015409808;30373.84813076009;30621.23446913845;30799;30952.226194345323;31093.68906310908;29869.253268668457;30448;30483;30563.08310750681;30639.387212256075;33308.16729581683;33506;33817.83091084529;33903.3219335607;33908.49137262894;29725.851370743007;29941.401171976875;30055.849720751256;30548.694513054586;30598.541021884244;22358.217462389388;23208.535829283657;23255.651165232197;23360.18239361614;23553.293401197992 03:03:44 INFO - PID 17096 | |2;jquery.html;559.1975515135778;568.1591244957463;572.4847637126586;573.5153795043187;580.7793038645315;21641.783582164175;22295.888520557393;22388;22399.440013999658;22458.43853903653;23257;25580.360490987736;25626.10308639198;25707.61438578423;25728.356791080234;6526.086347911296;6671.532992690513;6671.666416679171;6675.299093595176;6693.163354580677;5939.2575928009;6465.515086368518;6500.3174666660025;6556.770513032045;6619.271880093186;6445;6454.128692626506;6459.83850403739;6459.967700161493;6462.935370646303;6465.644389558573;6482.708278127472;6487.156669632965;6491.091247225392;6492.805215843532;6006.129111278884;6015.488683461911;6035.818925432244;6041.063635136561;6048.1834952281315;28587.285317867012;29012;29154.708452915413;29386.265343366373;29980.400391992258;2016.5059560407647;2057.804508571683;2065.5662310914727;2067.927622533219;2068.431181425105;18417.815821841745;18990.810091899042;18997.525061873424;19006.52483687905;19021.61956760857;2247.5280191159877;2366.7418247196238;2369.3484291819714;2399.6160614301803;2461.569225385568;140897;142888;143299.99999999895;144147.279263603;144176;14205.715885682228;14384.345800233106;14567.781483277759;14801.629959251104;14938;23552;23552.293431197093;23608.29175124749;23615;23626.637336266234;9978.950105249573;10104.343217690826;10188.235882308856;10302;10315.896841031643;9427.057294270655;9488.620455181715;9519.190868776093;9597.136257736836;9642.95178524117;7542.34068504928;8722.520261385558;9077;9181.403208791407;9319.347645664739;2633.5515466493353;2640.4587059652727;2662.4142688608476;2671.238696971354;2760.7377299156547;947.1806887042796;976.0093505092337;980.578351308944;994.5773046455228;1005.4269066631949;19406.902965485082;19499.805001950084;19784;19961.20155193776;23724.16965406237;1033.6433930294036;1066.893310668927;1072.973175670599;1077.3428208792786;1085.2349093888947;11203.327800332007;11221.326720396803;11245.775084498428;11337.206395552232;11447.198696091195;518180;523455;524285;524542;528669;119704;123241.38379308046;128170;130494.34752826176;131309.3434532821;1211.5880600595663;1212.3756265523264;1251.9186252893533;1261.905357098203;1269.3462866623697;20519.794802052387;20611.58776824485;20667.379978600668;20936.790632093485;21295.8935205323;57420;58040.70979645075;58118.41881581299;58763.82472350614;60118.39881601128;72741;73835.26164738284;73895.63052184705;74443.62778186075;74499;14380.209088500233;14750.114993100447;14794.63013424673;14815.18516481604;14950.551483455514;23274.76725232726;23490.177843775386;23518.764812352343;24372.756272437047;25038.37404064913;18681.906590467504;19105.331313403964;19213.807861921203;19281.4215573533;19284.421467356;17555.12224388801;17578.472645820646;17813.57491400711;18116.27534898589;18142.274309027493;19928;20196.495087622927;20551.177952882314;20628.96855157206;20728.79271207329;21277.36167914965;21584.892075539523;21664.458388540414;21701.240456583942;22302.77697223007;18247.81752182461;18865.622687545896;18905.432837014912;18911.338103166323;19062.523436914187 03:03:44 INFO - PID 17096 | |3;mootools.html;1311.5540716156506;1350.331585864997;1351.7972304154375;1369.2948131712176;1375.0580852116302;14761.70476590469;15261.542153735378;15356.07863528189;15366.308516116773;15395.69208615827;15647.608809779762;15906.045637261772;15925.362985480595;15941.52175434736;15949.441769538069;2660.840349579031;2700.203439985199;2702.094798242591;2702.459508098378;2703.0539311241105;2658.3885706287565;2742.053991372973;2796.8321900686024;2800.901968431105;2803.8457884816326;2598.7791037761717;2622.1084831157405;2625.5930330798756;2630.592258199982;2632.3813903732703;2591.326255173659;2608.9608655870084;2639.4984952858895;2651.827631203975;2652.82756620819;2621.4626001669803;2633.8288011279396;2634.6443230164064;2653.01838319821;2661.4144888124774;14507.70984580313;14755.483558075523;14831.70336593262;14859.182744949023;14860.331285092088;7977.202279772035;8236.546989915552;8252.958735206346;8256.628451719687;8267.090620031791;5374.946250537523;5433.157860531624;5516.338039435253;5577.3028371453565;5630.324361076697;1654.1811803157393;1680.5882558773058;1683.360323077235;1709.1881356355757;1717.6049508612964;26069.869650651814;26281.21156365312;26315.605265921025;26319.605205921926;26378.604320935392;6597.406233439014;6821.079154314203;6827.975803629396;6986.336298051677;7062;15770.369185232463;15772.290246938916;15774.369025239092;15790.289436975363;15793.605159871095;7655.617219139021;8263.132371101068;8452.788680283042;8463.619137138843;8472.068072512018;6206.193194884636;6482.546221764525;6656;6657.73369065232;7551.169371369145;5141.485851414828;5269.077911365495;5322.201669749568;5331.413544510101;5339.893202135935;1539.2303848075962;1551.7206902757612;1562.265735104499;1566.0681894272889;1567.529741077672;880.1726377205406;895.4716717136921;903.4759839293195;904.0923651096182;904.516083895113;7672.884906726404;7778;7852;7867.960660196777;7870.488418252795;655.7016557466342;663.6018388966678;715.6386025057312;718.4037249083353;722.5484072454716;4828.686135401188;5884.528867783054;5959.5828292020005;5980.372060933626;6097.2381099861095;20141;20623.896880515502;20637.690434643493;21547.569048619254;22230.666540001912;20319.593608127754;20358.18567257323;20410.795892041187;20656.277030303867;20716.378508644764;10408.47957602117;10550.788984220273;10767.676969690921;10802.351858888493;10833.349998999927;8418.705345312885;8832.470051796912;8986.955065224633;9270.907290927003;9397.530123493934;18765.6246875059;18879.716804248223;18970.43088707341;19046.7142992858;19138.138783754486;19220.327288544835;19283.710744339125;19348.32280870163;19358.612827743647;19483.02584870779;11717.296962182296;12019;12401.13192076582;12445.875541244472;12447.439865205906;14125.929370353082;14275.500357487437;14279.64300892486;14284.928575357057;14289.499867504588;11285.37930413835;11426.085913126764;11649.88350116488;11686.415679215836;11781.05751539892;11131.443427828739;11240.943795281299;11277.210595258253;11421.657350279505;11608.941955290169;10304.711911011123;10396.58413663445;10515.15878729715;10532.26274160832;10550.261481696514;7315.817104572428;7386.667599958122;7472.887906681295;7475.738349157971;7547.433942454228;6999.1950925644205;7037.753678621224;7037.964810175917;7062.364387205176;7117.217106118426 03:03:44 INFO - PID 17096 | |4;prototype.html;502.98491045268656;513.3839392728726;526.5024551798552;531.4340227657544;537.0547835808975;6395.648239346834;6472.676366181684;6507.609543427392;6511.739530418789;6512.283648798629;6328.208973878265;6378.968105159468;6402.327755585667;6428.003659432796;6444.7099880505375;5264.341957255353;5284.841454756363;5294.888073504569;5311.07056265154;5343.145096784515;4903.763516079388;5081.314022606956;5276.630635855492;5316.441773613773;5328.813491527797;5086.440491545927;5087.974560127194;5103.974480127594;5109.6678715883345;5131.486851314858;5078.365204349457;5096.719680417576;5111.1566591512355;5127.359409903903;5130.9230361544605;4467.262901621261;4559.794809233593;4565.360849481075;4568.583739040908;4573.588377046081;6291.905621415683;6299.779507717254;6307.148534947815;6308.83286591982;6339.175907132089;5840.970795145997;5862.912056319158;5872.060470324727;5872.295324561037;5874.9118763218585;6779.15260592426;6784.253732089466;6787.457003439713;6789.219239787439;6824.556403833735;4961.875953101166;4980.452150263468;5001.374003847218;5014.84955451337;5033.370828646419;6638.4689224861895;6894.207166175854;6984.755533556352;7053.30720627051;7122.430205583541;4913.31213630092;5076.796928122908;5085.186370180827;5116.948830511722;5124.9743751281;5148.330717006766;5155.484451554815;5163.586913047023;5170.586353091744;5188.226831315199;5749.195112684228;5774.942250577525;5839.357670656224;5865.530757539386;5866.530677545786;6745.831354216184;6769.458443324522;6807.285235050248;6818.852061600679;6825.897611535831;1772.6720556697053;1794.605186858889;1810.4924679319238;1810.7053456778467;1816.8644597126795;1184.691980085184;1230.6123571075082;1232.1559731583893;1240.348816871132;1248.7502499500135;5978.701064946736;6002.909956350658;6005.069214271794;6042.854786550799;6050.757969681164;6822.827385265875;6878.38094571481;6910.963355496716;6926.584404935619;6945.791626251221;5909.202257695156;5915.835028259947;5951;5955.731992060368;6033.939660603425;3622.8188590570894;3629.6007439181667;3657.5428071491065;3668.926621467609;3694.612065733113;6315.621062736251;6612.206535215805;6630.237522684856;6646.700898459581;6716.764913228111;6951.756688515878;6955.374016338453;7035.33164349386;7064.752733654297;7080.36276735086;6564.770233041916;6577.901331480031;6662.933370666231;6667.433268172065;6695.296993815677;6794.150731158606;6828;6845.691943862438;6905.309469053054;6979.406750426067;6820.556663816836;6841.760538381132;6845.452363810983;6878.828029299308;6898.171984424232;6610.719301840857;6628.171478565179;6690.264070952094;6733.898991515033;6757.189137303555;5100.846974590768;5113.6477220479;5122.692638441706;5129.48705129499;5131.358580177478;6601.9009714853355;6707.088479783433;6852.5545839522265;6898.172219333712;7032.437405007689;6582.144321238208;6589.2867854357255;6628.541720821411;6659.433948114464;6683.665816709243;6547.377999090078;6581.215800914589;6614.461263224344;6620.404163625297;6687.598744075371;6481.902771458337;6543.345665433419;6561.278259391559;6591.538592298493;6612.867742645217;6762.96618516924;6795.422389096982;6808.62552559614;6810.387065164159;6815.556988795713;6555.6066636002;6564.5076619252795;6567.868642627216;6568.80293591193;6579.440747536513 03:03:44 INFO - PID 17096 | |5;yui.html;893.1648908270768;916.3402350307779;919.4437365393936;924.269826836799;927.5269612497626;17388;18213.817861821393;18316.175772090253;19677.704834427495;20677.276295329666;20652.277170299043;20875.582488350225;21521.892390538025;21594.568108637817;21694.674579881314;921.230772305126;924.9583768730423;925.7870689741363;926.647873807952;973.5229737428645;965.9951700241525;994.5027486256871;996.2976101848179;997.9650712225074;999.0558921818885;1052.7105046112304;1053.031211285619;1055.4933631856713;1056.7041228456005;1056.9894301056968;1043.128987295609;1043.7651528406125;1043.88517263101;1044.5299615173203;1055.0082922053284;763.904511936008;766.31797700047;767.1024900866;769.0156599552525;775.3642013548892;19436.125374358046;19489;19805.9009704952;19850.900745496325;20327.695084573745;21204.575908481744;21266.46833829151;21779.782202177932;22267.331980040624;22281.554368912693;16361;16442.424515142033;16667.83332166687;16687.249073791707;16815.579610509714;3748.8313025913626;3781.6596506314563;3782.8865134046023;3783.375743002429;3784.2620688965662;130617.34691326578;131516;132166;133599.33200334033;133898;16058.919705401631;16135.596610084607;16391.590210244838;16410.83589164117;16562.50312490603;30344.54483182754;30822.53766193509;30850.69149308523;31099.689003110132;31295.217619559695;7837.725679601187;8034.718784842619;8864.157904999014;9102.907651081912;9168.954155229314;4515.932261016087;4651.209294419957;4653.930191047137;4661.510541393172;4682.719036857799;5199.376074871039;5269.710165940834;5275.340582427197;5277.736113194325;5317.707526086025;1053.5891002509027;1085.79369919715;1085.9076978456762;1088.4068182840213;1094.8795632480421;688.3804575881746;711.1856923822195;712.1774350625036;715.8425146467769;731.2687312687312;11353.54585816558;11966.22219555726;12043.458044388024;12103.515859365703;13744.862551374557;706.6678661029308;710.2435905760349;712.4692104382165;721.9241979592172;733.6698485681464;5632.098864181793;5750.453706897838;5893.381194974551;5988.431099045583;6198.566100372931;105976.47011764892;107550;109657;110901.44549277202;114945.4252728731;94621.5268923651;95814;96740.51629741947;99919;106050.46974765077;86607.56696216605;88219;88422;90351.99999999869;93275.067249328;12246.326452045223;12881.033922455666;12882.03384746129;12972.627755435835;12998.610041698765;40093;41189.382159267036;41563.337466501;41740.582594174884;41895;70041.64979175072;73548.63225683838;75113.62443187748;76056.23943760492;77240.6137969329;17788.55528611795;18616.81383186151;19180.904095479433;19480.318188863323;19490.415287541397;20875.478113047295;21478.892605537498;21993.67009494891;22212.666809998187;22440.663390048834;18735.063246837875;19024.334148304744;19124.426267212006;19217;19744.111514981574;19151.61696766029;19278.807211927702;19541.413757587296;19710.605787884448;20196.596068078263;18388.988605626284;18638;18723.531911702317;18932;18964.905175474585;13693.863061369259;13998.44006239739;14424.711505770036;14466.782998255245;14547.490837820626;14634.487792927197;14770.48303309379;15036.849631503545;15076.924615376853;15117.732340149065 03:03:44 INFO - PID 17096 | __end_tp_report 03:03:44 INFO - PID 17096 | __start_cc_report 03:03:44 INFO - PID 17096 | _x_x_mozilla_cycle_collect,386 03:03:44 INFO - PID 17096 | __end_cc_report 03:03:44 INFO - PID 17096 | __startTimestamp1504433024211__endTimestamp 03:03:44 INFO - PID 17096 | 03:03:44 INFO - PID 17096 | ------- Summary: start ------- 03:03:44 INFO - PID 17096 | Number of tests: 6 03:03:44 INFO - PID 17096 | 03:03:44 INFO - PID 17096 | [#0] dojo.html Cycles:165 Average:6341.69 Median:6507.71 stddev:745.26 (11.5%) stddev-sans-first:708.98 03:03:44 INFO - PID 17096 | Values: 3325.0 3337.9 3340.3 3381.7 3385.4 6392.6 6468.3 6488.9 6495.8 6523.7 6338.1 6431.4 6436.4 6437.4 6448.0 5859.3 5868.8 5882.1 5921.8 5927.4 5908.0 5909.2 5926.2 6017.3 6048.1 5880.4 5956.9 5959.8 5977.7 6017.8 5760.8 5822.4 5847.9 5915.3 5918.3 5731.3 5870.5 5889.7 5973.6 6012.5 6357.3 6373.3 6384.0 6433.0 6468.0 6725.4 6744.3 6779.8 6794.6 6821.1 6886.5 6896.6 6904.6 6923.1 6940.6 6375.1 6377.8 6378.5 6458.3 6466.7 7136.9 7156.9 7208.6 7226.1 7240.4 6798.0 6801.6 6810.3 6897.1 6932.1 6721.8 6769.3 6961.6 7015.2 7056.2 6777.6 6786.8 6803.0 6830.4 6859.3 6910.0 6912.3 6914.8 6947.1 6951.0 5387.9 5457.6 5468.5 5479.4 5488.3 4655.8 4657.6 4754.4 4782.8 4796.8 5960.7 5966.1 5971.1 5979.5 6038.2 6848.3 6935.1 6956.1 6991.6 7015.7 6027.8 6070.4 6083.9 6091.7 6101.7 5918.9 5921.0 5979.4 6007.0 6013.3 6694.8 6703.2 6764.5 6776.1 6780.9 6832.5 6892.6 6913.0 6920.4 6922.6 6540.5 6568.8 6609.6 6663.4 6750.1 6883.8 6901.5 6916.2 6937.5 6955.2 6970.7 6987.5 7063.9 7066.4 7074.8 6612.7 6674.3 6686.9 6767.9 6774.4 6423.2 6492.9 6503.1 6508.5 6796.6 6216.4 6507.7 6533.1 6566.4 6568.1 6728.0 6763.5 6768.5 6786.0 6806.8 6263.4 6366.4 6368.4 6485.1 6567.7 03:03:44 INFO - PID 17096 | 03:03:44 INFO - PID 17096 | [#1] ext.html Cycles:180 Average:37592.22 Median:33905.91 stddev:56836.94 (167.6%) stddev-sans-first:56929.17 03:03:44 INFO - PID 17096 | Values: 777.7 840.7 853.4 853.7 1124.3 29689.4 30142.4 39101.0 39562.0 39676.4 40505.4 40814.6 40894.4 40903.4 41184.6 7189.9 7583.4 7788.8 7792.6 7803.3 7578.1 7690.9 7705.7 7707.0 7707.3 7686.6 7727.4 7743.7 7747.9 7754.6 7659.8 7662.1 7684.6 7688.0 7695.3 7631.9 7672.5 7686.7 7687.0 7695.5 36382.8 36604.3 36627.5 36628.3 36664.0 3454.2 3479.0 3488.2 3496.0 3496.7 30998.5 31026.0 31055.8 31062.1 31115.7 4887.5 5048.8 5071.7 5080.1 5093.8 192749.0 193328.0 194321.0 194458.0 194803.0 46509.0 46512.3 46631.5 46668.5 46679.3 48068.0 48163.8 48220.5 48232.3 48240.8 12355.2 15238.9 15481.8 15488.5 15508.8 9712.6 12185.8 12262.6 12306.0 12320.9 13417.5 13443.1 13446.5 13508.6 13548.3 5258.2 5443.1 5461.2 5517.6 5595.9 967.6 979.3 980.5 981.2 996.2 21841.9 22067.4 28574.6 28665.0 28860.0 1048.6 1052.4 1067.1 1081.6 1090.9 11832.1 11863.7 11963.6 12011.2 12111.9 246646.0 249042.0 249535.0 249834.0 250376.0 48859.0 48962.3 49113.0 49144.0 49178.8 1406.6 1423.3 1429.5 1431.4 1439.3 21713.8 21908.0 21964.8 22086.4 22474.4 34471.0 34687.1 34713.7 34735.7 34749.8 209001.0 209546.0 209562.0 211087.0 211420.0 31085.2 31965.2 31967.5 32359.2 32370.4 40374.4 40423.0 40544.0 40913.8 40984.6 30373.8 30621.2 30799.0 30952.2 31093.7 29869.3 30448.0 30483.0 30563.1 30639.4 33308.2 33506.0 33817.8 33903.3 33908.5 29725.9 29941.4 30055.8 30548.7 30598.5 22358.2 23208.5 23255.7 23360.2 23553.3 03:03:44 INFO - PID 17096 | 03:03:44 INFO - PID 17096 | [#2] jquery.html Cycles:180 Average:36670.92 Median:23265.88 stddev:88231.90 (379.2%) stddev-sans-first:88437.76 03:03:44 INFO - PID 17096 | Values: 559.2 568.2 572.5 573.5 580.8 21641.8 22295.9 22388.0 22399.4 22458.4 23257.0 25580.4 25626.1 25707.6 25728.4 6526.1 6671.5 6671.7 6675.3 6693.2 5939.3 6465.5 6500.3 6556.8 6619.3 6445.0 6454.1 6459.8 6460.0 6462.9 6465.6 6482.7 6487.2 6491.1 6492.8 6006.1 6015.5 6035.8 6041.1 6048.2 28587.3 29012.0 29154.7 29386.3 29980.4 2016.5 2057.8 2065.6 2067.9 2068.4 18417.8 18990.8 18997.5 19006.5 19021.6 2247.5 2366.7 2369.3 2399.6 2461.6 140897.0 142888.0 143300.0 144147.3 144176.0 14205.7 14384.3 14567.8 14801.6 14938.0 23552.0 23552.3 23608.3 23615.0 23626.6 9979.0 10104.3 10188.2 10302.0 10315.9 9427.1 9488.6 9519.2 9597.1 9643.0 7542.3 8722.5 9077.0 9181.4 9319.3 2633.6 2640.5 2662.4 2671.2 2760.7 947.2 976.0 980.6 994.6 1005.4 19406.9 19499.8 19784.0 19961.2 23724.2 1033.6 1066.9 1073.0 1077.3 1085.2 11203.3 11221.3 11245.8 11337.2 11447.2 518180.0 523455.0 524285.0 524542.0 528669.0 119704.0 123241.4 128170.0 130494.3 131309.3 1211.6 1212.4 1251.9 1261.9 1269.3 20519.8 20611.6 20667.4 20936.8 21295.9 57420.0 58040.7 58118.4 58763.8 60118.4 72741.0 73835.3 73895.6 74443.6 74499.0 14380.2 14750.1 14794.6 14815.2 14950.6 23274.8 23490.2 23518.8 24372.8 25038.4 18681.9 19105.3 19213.8 19281.4 19284.4 17555.1 17578.5 17813.6 18116.3 18142.3 19928.0 20196.5 20551.2 20629.0 20728.8 21277.4 21584.9 21664.5 21701.2 22302.8 18247.8 18865.6 18905.4 18911.3 19062.5 03:03:44 INFO - PID 17096 | 03:03:44 INFO - PID 17096 | [#3] mootools.html Cycles:180 Average:9322.78 Median:2623.85 stddev:6594.68 (251.3%) stddev-sans-first:6585.71 03:03:44 INFO - PID 17096 | Values: 1311.6 1350.3 1351.8 1369.3 1375.1 14761.7 15261.5 15356.1 15366.3 15395.7 15647.6 15906.0 15925.4 15941.5 15949.4 2660.8 2700.2 2702.1 2702.5 2703.1 2658.4 2742.1 2796.8 2800.9 2803.8 2598.8 2622.1 2625.6 2630.6 2632.4 2591.3 2609.0 2639.5 2651.8 2652.8 2621.5 2633.8 2634.6 2653.0 2661.4 14507.7 14755.5 14831.7 14859.2 14860.3 7977.2 8236.5 8253.0 8256.6 8267.1 5374.9 5433.2 5516.3 5577.3 5630.3 1654.2 1680.6 1683.4 1709.2 1717.6 26069.9 26281.2 26315.6 26319.6 26378.6 6597.4 6821.1 6828.0 6986.3 7062.0 15770.4 15772.3 15774.4 15790.3 15793.6 7655.6 8263.1 8452.8 8463.6 8472.1 6206.2 6482.5 6656.0 6657.7 7551.2 5141.5 5269.1 5322.2 5331.4 5339.9 1539.2 1551.7 1562.3 1566.1 1567.5 880.2 895.5 903.5 904.1 904.5 7672.9 7778.0 7852.0 7868.0 7870.5 655.7 663.6 715.6 718.4 722.5 4828.7 5884.5 5959.6 5980.4 6097.2 20141.0 20623.9 20637.7 21547.6 22230.7 20319.6 20358.2 20410.8 20656.3 20716.4 10408.5 10550.8 10767.7 10802.4 10833.3 8418.7 8832.5 8987.0 9270.9 9397.5 18765.6 18879.7 18970.4 19046.7 19138.1 19220.3 19283.7 19348.3 19358.6 19483.0 11717.3 12019.0 12401.1 12445.9 12447.4 14125.9 14275.5 14279.6 14284.9 14289.5 11285.4 11426.1 11649.9 11686.4 11781.1 11131.4 11240.9 11277.2 11421.7 11608.9 10304.7 10396.6 10515.2 10532.3 10550.3 7315.8 7386.7 7472.9 7475.7 7547.4 6999.2 7037.8 7038.0 7062.4 7117.2 03:03:44 INFO - PID 17096 | 03:03:44 INFO - PID 17096 | [#4] prototype.html Cycles:180 Average:5657.83 Median:6333.69 stddev:1585.46 (25.0%) stddev-sans-first:1541.98 03:03:44 INFO - PID 17096 | Values: 503.0 513.4 526.5 531.4 537.1 6395.6 6472.7 6507.6 6511.7 6512.3 6328.2 6379.0 6402.3 6428.0 6444.7 5264.3 5284.8 5294.9 5311.1 5343.1 4903.8 5081.3 5276.6 5316.4 5328.8 5086.4 5088.0 5104.0 5109.7 5131.5 5078.4 5096.7 5111.2 5127.4 5130.9 4467.3 4559.8 4565.4 4568.6 4573.6 6291.9 6299.8 6307.1 6308.8 6339.2 5841.0 5862.9 5872.1 5872.3 5874.9 6779.2 6784.3 6787.5 6789.2 6824.6 4961.9 4980.5 5001.4 5014.8 5033.4 6638.5 6894.2 6984.8 7053.3 7122.4 4913.3 5076.8 5085.2 5116.9 5125.0 5148.3 5155.5 5163.6 5170.6 5188.2 5749.2 5774.9 5839.4 5865.5 5866.5 6745.8 6769.5 6807.3 6818.9 6825.9 1772.7 1794.6 1810.5 1810.7 1816.9 1184.7 1230.6 1232.2 1240.3 1248.8 5978.7 6002.9 6005.1 6042.9 6050.8 6822.8 6878.4 6911.0 6926.6 6945.8 5909.2 5915.8 5951.0 5955.7 6033.9 3622.8 3629.6 3657.5 3668.9 3694.6 6315.6 6612.2 6630.2 6646.7 6716.8 6951.8 6955.4 7035.3 7064.8 7080.4 6564.8 6577.9 6662.9 6667.4 6695.3 6794.2 6828.0 6845.7 6905.3 6979.4 6820.6 6841.8 6845.5 6878.8 6898.2 6610.7 6628.2 6690.3 6733.9 6757.2 5100.8 5113.6 5122.7 5129.5 5131.4 6601.9 6707.1 6852.6 6898.2 7032.4 6582.1 6589.3 6628.5 6659.4 6683.7 6547.4 6581.2 6614.5 6620.4 6687.6 6481.9 6543.3 6561.3 6591.5 6612.9 6763.0 6795.4 6808.6 6810.4 6815.6 6555.6 6564.5 6567.9 6568.8 6579.4 03:03:44 INFO - PID 17096 | 03:03:44 INFO - PID 17096 | [#5] yui.html Cycles:180 Average:24446.95 Median:21372.68 stddev:33040.55 (154.6%) stddev-sans-first:33085.90 03:03:44 INFO - PID 17096 | Values: 893.2 916.3 919.4 924.3 927.5 17388.0 18213.8 18316.2 19677.7 20677.3 20652.3 20875.6 21521.9 21594.6 21694.7 921.2 925.0 925.8 926.6 973.5 966.0 994.5 996.3 998.0 999.1 1052.7 1053.0 1055.5 1056.7 1057.0 1043.1 1043.8 1043.9 1044.5 1055.0 763.9 766.3 767.1 769.0 775.4 19436.1 19489.0 19805.9 19850.9 20327.7 21204.6 21266.5 21779.8 22267.3 22281.6 16361.0 16442.4 16667.8 16687.2 16815.6 3748.8 3781.7 3782.9 3783.4 3784.3 130617.3 131516.0 132166.0 133599.3 133898.0 16058.9 16135.6 16391.6 16410.8 16562.5 30344.5 30822.5 30850.7 31099.7 31295.2 7837.7 8034.7 8864.2 9102.9 9169.0 4515.9 4651.2 4653.9 4661.5 4682.7 5199.4 5269.7 5275.3 5277.7 5317.7 1053.6 1085.8 1085.9 1088.4 1094.9 688.4 711.2 712.2 715.8 731.3 11353.5 11966.2 12043.5 12103.5 13744.9 706.7 710.2 712.5 721.9 733.7 5632.1 5750.5 5893.4 5988.4 6198.6 105976.5 107550.0 109657.0 110901.4 114945.4 94621.5 95814.0 96740.5 99919.0 106050.5 86607.6 88219.0 88422.0 90352.0 93275.1 12246.3 12881.0 12882.0 12972.6 12998.6 40093.0 41189.4 41563.3 41740.6 41895.0 70041.6 73548.6 75113.6 76056.2 77240.6 17788.6 18616.8 19180.9 19480.3 19490.4 20875.5 21478.9 21993.7 22212.7 22440.7 18735.1 19024.3 19124.4 19217.0 19744.1 19151.6 19278.8 19541.4 19710.6 20196.6 18389.0 18638.0 18723.5 18932.0 18964.9 13693.9 13998.4 14424.7 14466.8 14547.5 14634.5 14770.5 15036.8 15076.9 15117.7 03:03:44 INFO - PID 17096 | -------- Summary: end -------- 03:03:44 INFO - PID 17096 | 03:03:44 INFO - TEST-INFO | 17096: exit 0 03:03:44 INFO - TEST-OK | dromaeo_css | took 1111021ms 03:03:44 INFO - TEST-START | kraken 03:03:44 INFO - Initialising browser for kraken test... 03:03:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54292/getInfo.html -profile /tmp/tmpAKsCKY/profile 03:03:44 INFO - TEST-INFO | started process 17466 (/builds/slave/test/build/application/firefox/firefox http://localhost:54292/getInfo.html) 03:03:50 INFO - TEST-INFO | 17466: exit 0 03:03:50 INFO - Browser initialized. 03:03:50 INFO - Running cycle 1/1 for kraken test... 03:03:50 INFO - Using env: {'DISPLAY': ':0', 03:03:50 INFO - 'HOME': '/home/cltbld', 03:03:50 INFO - 'JSGC_DISABLE_POISONING': '1', 03:03:50 INFO - 'LANG': 'en_US.UTF-8', 03:03:50 INFO - 'LANGUAGE': 'en_US:en', 03:03:50 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:03:50 INFO - 'LOGNAME': 'cltbld', 03:03:50 INFO - 'MAIL': '/var/mail/cltbld', 03:03:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:03:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:03:50 INFO - 'MOZ_CRASHREPORTER': '1', 03:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:50 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:03:50 INFO - 'MOZ_NO_REMOTE': '1', 03:03:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:03:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:50 INFO - 'NO_EM_RESTART': '1', 03:03:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:50 INFO - 'PWD': '/builds/slave/test', 03:03:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:03:50 INFO - 'RUST_BACKTRACE': 'full', 03:03:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:03:50 INFO - 'SHELL': '/bin/bash', 03:03:50 INFO - 'SHLVL': '1', 03:03:50 INFO - 'STYLO_FORCE_ENABLED': '1', 03:03:50 INFO - 'STYLO_THREADS': '1', 03:03:50 INFO - 'TERM': 'linux', 03:03:50 INFO - 'TMOUT': '86400', 03:03:50 INFO - 'USER': 'cltbld', 03:03:50 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034', 03:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:50 INFO - '_': '/tools/buildbot/bin/python'} 03:03:50 INFO - TEST-INFO | started process 17648 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAKsCKY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:03:51 INFO - PID 17648 | 03:03:51 INFO - PID 17648 | (/builds/slave/test/build/application/firefox/firefox:17700): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:03:51 INFO - PID 17648 | 03:04:01 INFO - PID 17648 | RSS: Main: 173215744 03:04:01 INFO - PID 17648 | 03:04:04 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54292/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:05 INFO - PID 17648 | RSS: Main: 177262592 03:04:05 INFO - PID 17648 | 03:04:07 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54292/tests/kraken/driver.html?testName=audio-dft) 03:04:08 INFO - PID 17648 | RSS: Main: 181886976 03:04:08 INFO - PID 17648 | 03:04:11 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54292/tests/kraken/driver.html?testName=audio-fft) 03:04:12 INFO - PID 17648 | RSS: Main: 174456832 03:04:12 INFO - PID 17648 | 03:04:14 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54292/tests/kraken/driver.html?testName=audio-oscillator) 03:04:14 INFO - PID 17648 | RSS: Main: 175202304 03:04:14 INFO - PID 17648 | 03:04:16 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54292/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:04:17 INFO - PID 17648 | RSS: Main: 175312896 03:04:17 INFO - PID 17648 | 03:04:20 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54292/tests/kraken/driver.html?testName=imaging-darkroom) 03:04:21 INFO - PID 17648 | RSS: Main: 176209920 03:04:21 INFO - PID 17648 | 03:04:24 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54292/tests/kraken/driver.html?testName=imaging-desaturate) 03:04:25 INFO - PID 17648 | RSS: Main: 176852992 03:04:25 INFO - PID 17648 | 03:04:28 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54292/tests/kraken/driver.html?testName=json-parse-financial) 03:04:28 INFO - PID 17648 | RSS: Main: 176885760 03:04:28 INFO - PID 17648 | 03:04:30 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54292/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:04:31 INFO - PID 17648 | RSS: Main: 177074176 03:04:31 INFO - PID 17648 | 03:04:32 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54292/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:04:33 INFO - PID 17648 | RSS: Main: 177410048 03:04:33 INFO - PID 17648 | 03:04:35 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54292/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:04:36 INFO - PID 17648 | RSS: Main: 173895680 03:04:36 INFO - PID 17648 | 03:04:38 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54292/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:04:39 INFO - PID 17648 | RSS: Main: 174080000 03:04:39 INFO - PID 17648 | 03:04:42 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54292/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:04:43 INFO - PID 17648 | RSS: Main: 174915584 03:04:43 INFO - PID 17648 | 03:04:44 INFO - PID 17648 | Cycle 1(1): loaded http://localhost:54292/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54292/tests/kraken/driver.html?testName=ai-astar) 03:04:45 INFO - PID 17648 | RSS: Main: 175779840 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | __start_tp_report 03:04:45 INFO - PID 17648 | _x_x_mozilla_page_load 03:04:45 INFO - PID 17648 | _x_x_mozilla_page_load_details 03:04:45 INFO - PID 17648 | |i|pagename|runs| 03:04:45 INFO - PID 17648 | |0;ai-astar;102;108;97;107;108;110;111;107;110;112 03:04:45 INFO - PID 17648 | |1;audio-beat-detection;151;123;140;186;116;139;144;116;174;117 03:04:45 INFO - PID 17648 | |2;audio-dft;163;163;163;164;162;164;163;165;166;164 03:04:45 INFO - PID 17648 | |3;audio-fft;73;75;74;173;74;75;75;75;154;74 03:04:45 INFO - PID 17648 | |4;audio-oscillator;79;79;134;79;79;83;126;81;80;133 03:04:45 INFO - PID 17648 | |5;imaging-gaussian-blur;114;115;114;114;114;114;114;114;113;117 03:04:45 INFO - PID 17648 | |6;imaging-darkroom;148;149;148;147;147;150;147;147;147;148 03:04:45 INFO - PID 17648 | |7;imaging-desaturate;94;93;93;94;94;94;94;94;95;94 03:04:45 INFO - PID 17648 | |8;json-parse-financial;54;53;52;51;53;53;52;53;53;52 03:04:45 INFO - PID 17648 | |9;json-stringify-tinderbox;38;39;38;38;38;38;38;38;38;38 03:04:45 INFO - PID 17648 | |10;stanford-crypto-aes;75;75;75;76;74;77;75;79;74;75 03:04:45 INFO - PID 17648 | |11;stanford-crypto-ccm;124;116;129;120;124;129;117;119;129;155 03:04:45 INFO - PID 17648 | |12;stanford-crypto-pbkdf2;182;175;179;179;171;174;173;176;173;181 03:04:45 INFO - PID 17648 | |13;stanford-crypto-sha256-iterative;66;67;65;64;64;63;65;65;62;63 03:04:45 INFO - PID 17648 | __end_tp_report 03:04:45 INFO - PID 17648 | __start_cc_report 03:04:45 INFO - PID 17648 | _x_x_mozilla_cycle_collect,933 03:04:45 INFO - PID 17648 | __end_cc_report 03:04:45 INFO - PID 17648 | __startTimestamp1504433085212__endTimestamp 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | ------- Summary: start ------- 03:04:45 INFO - PID 17648 | Number of tests: 14 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#0] ai-astar Cycles:10 Average:107.20 Median:110.00 stddev:4.54 (4.1%) stddev-sans-first:4.41 03:04:45 INFO - PID 17648 | Values: 102.0 108.0 97.0 107.0 108.0 110.0 111.0 107.0 110.0 112.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:142.00 stddev:24.44 (17.2%) stddev-sans-first:25.63 03:04:45 INFO - PID 17648 | Values: 151.0 123.0 140.0 186.0 116.0 139.0 144.0 116.0 174.0 117.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#2] audio-dft Cycles:10 Average:163.70 Median:164.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 03:04:45 INFO - PID 17648 | Values: 163.0 163.0 163.0 164.0 162.0 164.0 163.0 165.0 166.0 164.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#3] audio-fft Cycles:10 Average:92.20 Median:74.50 stddev:37.85 (50.8%) stddev-sans-first:39.50 03:04:45 INFO - PID 17648 | Values: 73.0 75.0 74.0 173.0 74.0 75.0 75.0 75.0 154.0 74.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:79.00 stddev:24.75 (31.3%) stddev-sans-first:25.54 03:04:45 INFO - PID 17648 | Values: 79.0 79.0 134.0 79.0 79.0 83.0 126.0 81.0 80.0 133.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:1.06 (0.9%) stddev-sans-first:1.12 03:04:45 INFO - PID 17648 | Values: 114.0 115.0 114.0 114.0 114.0 114.0 114.0 114.0 113.0 117.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:1.03 (0.7%) stddev-sans-first:1.09 03:04:45 INFO - PID 17648 | Values: 148.0 149.0 148.0 147.0 147.0 150.0 147.0 147.0 147.0 148.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 03:04:45 INFO - PID 17648 | Values: 94.0 93.0 93.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:53.00 stddev:0.84 (1.6%) stddev-sans-first:0.73 03:04:45 INFO - PID 17648 | Values: 54.0 53.0 52.0 51.0 53.0 53.0 52.0 53.0 53.0 52.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.32 (0.8%) stddev-sans-first:0.33 03:04:45 INFO - PID 17648 | Values: 38.0 39.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:75.00 stddev:1.51 (2.0%) stddev-sans-first:1.59 03:04:45 INFO - PID 17648 | Values: 75.0 75.0 75.0 76.0 74.0 77.0 75.0 79.0 74.0 75.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#11] stanford-crypto-ccm Cycles:10 Average:126.20 Median:126.50 stddev:11.26 (8.9%) stddev-sans-first:11.92 03:04:45 INFO - PID 17648 | Values: 124.0 116.0 129.0 120.0 124.0 129.0 117.0 119.0 129.0 155.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.30 Median:177.50 stddev:3.74 (2.1%) stddev-sans-first:3.35 03:04:45 INFO - PID 17648 | Values: 182.0 175.0 179.0 179.0 171.0 174.0 173.0 176.0 173.0 181.0 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:1.51 (2.3%) stddev-sans-first:1.48 03:04:45 INFO - PID 17648 | Values: 66.0 67.0 65.0 64.0 64.0 63.0 65.0 65.0 62.0 63.0 03:04:45 INFO - PID 17648 | -------- Summary: end -------- 03:04:45 INFO - PID 17648 | 03:04:45 INFO - PID 17648 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:04:45 INFO - TEST-INFO | 17648: exit 0 03:04:45 INFO - TEST-OK | kraken | took 60980ms 03:04:45 INFO - SUITE-END | took 1172s 03:04:46 INFO - Completed test suite (00:19:32) 03:04:46 INFO - javascript benchmark 03:04:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8641.608792539524, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.002499250226, 3337.9165520861975, 3340.2818394045275, 3381.6618338166204, 3385.390629686657, 6392.6484043377595, 6468.32082631324, 6488.870222595545, 6495.8051258462265, 6523.673816309183, 6338.144350512679, 6431.38901804329, 6436.388543088399, 6437.388448097423, 6448.0327950807405, 5859.296884373881, 5868.823935281948, 5882.058870580708, 5921.763129474837, 5927.436893495111, 5908.0, 5909.20225769522, 5926.170336152942, 6017.277926648809, 6048.092786082079, 5880.3825598312205, 5956.851078723023, 5959.791407300745, 5977.701114944235, 6017.759289628431, 5760.798372057, 5822.3886491918165, 5847.912281315784, 5915.349311575723, 5918.348981612019, 5731.340895796995, 5870.501007414332, 5889.676067816268, 5973.6415815050805, 6012.549058820606, 6357.300696923335, 6373.298937116913, 6384.042393640944, 6433.035044743326, 6468.0, 6725.394714475672, 6744.258131605519, 6779.796606101825, 6794.592324460499, 6821.147356580427, 6886.48351373644, 6896.551724137915, 6904.620245886475, 6923.134608173978, 6940.583564986117, 6375.075614035985, 6377.808665740035, 6378.489720822323, 6458.2895881453005, 6466.74133034678, 7136.857262854714, 7156.92843071573, 7208.639568021578, 7226.0606121204955, 7240.420766338681, 6797.9660101700165, 6801.591904485647, 6810.250872404032, 6897.1033765611155, 6932.064171336805, 6721.764738234138, 6769.323067693192, 6961.582305061713, 7015.193252775894, 7056.15326160864, 6777.627230502271, 6786.76246331376, 6802.97955306708, 6830.385265326145, 6859.279775623588, 6910.0, 6912.343327383891, 6914.792556223321, 6947.131608548932, 6951.0, 5387.865303367369, 5457.617966742369, 5468.4531546845, 5479.369872464717, 5488.286522752017, 4655.837045703384, 4657.5575320344515, 4754.358161648133, 4782.760861956958, 4796.832110876103, 5960.6721630309885, 5966.134910437962, 5971.104334349882, 5979.521638269015, 6038.245219347582, 6848.280930502325, 6935.063766391472, 6956.130483689539, 6991.615461149686, 7015.719371225196, 6027.758889644365, 6070.3929607038945, 6083.908741368883, 6091.664958427329, 6101.725422356005, 5918.881622367615, 5920.970395148083, 5979.402059794073, 6007.008843540767, 6013.278406591149, 6694.799156025327, 6703.22912865017, 6764.458843292525, 6776.085228494188, 6780.932190678129, 6832.487563432663, 6892.551984121016, 6913.0, 6920.446364290744, 6922.550034247758, 6540.476761859135, 6568.770093046721, 6609.636469994197, 6663.36698013688, 6750.088738020305, 6883.793486195422, 6901.482388820959, 6916.23921368639, 6937.514373993772, 6955.1653801544135, 6970.65146742671, 6987.475939304674, 7063.894041589278, 7066.364027237573, 7074.787756367317, 6612.669366531751, 6674.265830758515, 6686.933130668631, 6767.86464270702, 6774.356436138559, 6423.197100362455, 6492.870142597028, 6503.057056726842, 6508.511861610493, 6796.558223715443, 6216.409441103088, 6507.707153178024, 6533.118029066015, 6566.409023187936, 6568.146141001639, 6727.966360168168, 6763.458923286027, 6768.492363072691, 6786.049953006682, 6806.7957961261245, 6263.3736626337, 6366.395192456709, 6368.42684158428, 6485.092087107903, 6567.737290508326], "value": 6287.142985220955, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.6772639354665, 840.6595328891799, 853.3599800149888, 853.7012045783972, 1124.3254047571454, 29689.40621187575, 30142.397152056947, 39101.0, 39561.99999999993, 39676.40485392721, 40505.392419113734, 40814.591854081526, 40894.38658420126, 40903.38644920329, 41184.58815411845, 7189.928100718978, 7583.355414789736, 7788.766337009871, 7792.610369481532, 7803.258690424401, 7578.0906291244855, 7690.884636730453, 7705.653245603933, 7707.0, 7707.344875685573, 7686.5772382518935, 7727.381809455258, 7743.728969486069, 7747.922520774805, 7754.573498457583, 7659.846803063935, 7662.080550333968, 7684.615769211545, 7688.038995125609, 7695.268949449794, 7631.923680763232, 7672.539647621104, 7686.654100565488, 7687.0391201099865, 7695.4997925134685, 36382.81808590967, 36604.26791464182, 36627.4505882412, 36628.267434651425, 36664.0, 3454.1709989602564, 3479.025872755607, 3488.197714525648, 3496.021114088059, 3496.6503349665086, 30998.535021974687, 31026.0, 31055.84472077625, 31062.0681379559, 31115.688843111504, 4887.511248875119, 5048.7728052237735, 5071.670341427833, 5080.110980578384, 5093.770780314858, 192749.0, 193328.0, 194321.0, 194458.02770986196, 194803.0, 46509.0, 46512.3023154653, 46631.533684663395, 46668.5333146671, 46679.29981050287, 48068.0, 48163.759181203866, 48220.5177948216, 48232.27651585229, 48240.758796205795, 12355.196912200858, 15238.923805381124, 15481.845181548266, 15488.5353439397, 15508.767368489256, 9712.562934667956, 12185.756284874431, 12262.570810021607, 12306.015518758479, 12320.938395307967, 13417.463301468028, 13443.126196797177, 13446.529371471952, 13508.594742157751, 13548.322583870768, 5258.211268309784, 5443.129099344165, 5461.2354270402175, 5517.5585953123655, 5595.860103497445, 967.6468089147551, 979.299800642544, 980.5391466011106, 981.2101258486987, 996.1980605612551, 21841.890790545945, 22067.448313792283, 28574.571381429294, 28665.0, 28860.0, 1048.6172547020283, 1052.4053909541085, 1067.1089640150406, 1081.6376513867785, 1090.9454527273604, 11832.112591555668, 11863.703407414883, 11963.581274655346, 12011.219270747373, 12111.939440302742, 246646.0, 249042.0, 249535.0, 249834.0, 250376.0, 48859.0, 48962.26556601654, 49113.0, 49144.0, 49178.75410622924, 1406.6413064668443, 1423.309694798022, 1429.4996751137019, 1431.434583339575, 1439.2803598200899, 21713.782862171178, 21908.0, 21964.780352196914, 22086.447838804157, 22474.438139046655, 34471.0, 34687.13282167966, 34713.65286347104, 34735.652643474255, 34749.82625086858, 209001.0, 209546.0, 209562.0, 211087.0, 211420.0, 31085.22286942754, 31965.20086997844, 31967.520487192247, 32359.191020224684, 32370.35259294848, 40374.39438408367, 40423.0, 40544.0, 40913.795431022656, 40984.59015409808, 30373.84813076009, 30621.23446913845, 30799.0, 30952.226194345323, 31093.68906310908, 29869.253268668457, 30448.0, 30483.0, 30563.08310750681, 30639.387212256075, 33308.16729581683, 33506.0, 33817.83091084529, 33903.3219335607, 33908.49137262894, 29725.851370743007, 29941.401171976875, 30055.849720751256, 30548.694513054586, 30598.541021884244, 22358.217462389388, 23208.535829283657, 23255.651165232197, 23360.18239361614, 23553.293401197992], "value": 16682.9548490043, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.1975515135778, 568.1591244957463, 572.4847637126586, 573.5153795043187, 580.7793038645315, 21641.783582164175, 22295.888520557393, 22388.0, 22399.440013999658, 22458.43853903653, 23257.0, 25580.360490987736, 25626.10308639198, 25707.61438578423, 25728.356791080234, 6526.086347911296, 6671.532992690513, 6671.666416679171, 6675.299093595176, 6693.163354580677, 5939.2575928009, 6465.515086368518, 6500.3174666660025, 6556.770513032045, 6619.271880093186, 6445.0, 6454.128692626506, 6459.83850403739, 6459.967700161493, 6462.935370646303, 6465.644389558573, 6482.708278127472, 6487.156669632965, 6491.091247225392, 6492.805215843532, 6006.129111278884, 6015.488683461911, 6035.818925432244, 6041.063635136561, 6048.1834952281315, 28587.285317867012, 29012.0, 29154.708452915413, 29386.265343366373, 29980.400391992258, 2016.5059560407647, 2057.804508571683, 2065.5662310914727, 2067.927622533219, 2068.431181425105, 18417.815821841745, 18990.810091899042, 18997.525061873424, 19006.52483687905, 19021.61956760857, 2247.5280191159877, 2366.7418247196238, 2369.3484291819714, 2399.6160614301803, 2461.569225385568, 140897.0, 142888.0, 143299.99999999895, 144147.279263603, 144176.0, 14205.715885682228, 14384.345800233106, 14567.781483277759, 14801.629959251104, 14938.0, 23552.0, 23552.293431197093, 23608.29175124749, 23615.0, 23626.637336266234, 9978.950105249573, 10104.343217690826, 10188.235882308856, 10302.0, 10315.896841031643, 9427.057294270655, 9488.620455181715, 9519.190868776093, 9597.136257736836, 9642.95178524117, 7542.34068504928, 8722.520261385558, 9077.0, 9181.403208791407, 9319.347645664739, 2633.5515466493353, 2640.4587059652727, 2662.4142688608476, 2671.238696971354, 2760.7377299156547, 947.1806887042796, 976.0093505092337, 980.578351308944, 994.5773046455228, 1005.4269066631949, 19406.902965485082, 19499.805001950084, 19784.0, 19961.20155193776, 23724.16965406237, 1033.6433930294036, 1066.893310668927, 1072.973175670599, 1077.3428208792786, 1085.2349093888947, 11203.327800332007, 11221.326720396803, 11245.775084498428, 11337.206395552232, 11447.198696091195, 518180.0, 523455.0, 524285.0, 524542.0, 528669.0, 119704.0, 123241.38379308046, 128170.0, 130494.34752826176, 131309.3434532821, 1211.5880600595663, 1212.3756265523264, 1251.9186252893533, 1261.905357098203, 1269.3462866623697, 20519.794802052387, 20611.58776824485, 20667.379978600668, 20936.790632093485, 21295.8935205323, 57420.0, 58040.70979645075, 58118.41881581299, 58763.82472350614, 60118.39881601128, 72741.0, 73835.26164738284, 73895.63052184705, 74443.62778186075, 74499.0, 14380.209088500233, 14750.114993100447, 14794.63013424673, 14815.18516481604, 14950.551483455514, 23274.76725232726, 23490.177843775386, 23518.764812352343, 24372.756272437047, 25038.37404064913, 18681.906590467504, 19105.331313403964, 19213.807861921203, 19281.4215573533, 19284.421467356, 17555.12224388801, 17578.472645820646, 17813.57491400711, 18116.27534898589, 18142.274309027493, 19928.0, 20196.495087622927, 20551.177952882314, 20628.96855157206, 20728.79271207329, 21277.36167914965, 21584.892075539523, 21664.458388540414, 21701.240456583942, 22302.77697223007, 18247.81752182461, 18865.622687545896, 18905.432837014912, 18911.338103166323, 19062.523436914187], "value": 12327.154742054032, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.5540716156506, 1350.331585864997, 1351.7972304154375, 1369.2948131712176, 1375.0580852116302, 14761.70476590469, 15261.542153735378, 15356.07863528189, 15366.308516116773, 15395.69208615827, 15647.608809779762, 15906.045637261772, 15925.362985480595, 15941.52175434736, 15949.441769538069, 2660.840349579031, 2700.203439985199, 2702.094798242591, 2702.459508098378, 2703.0539311241105, 2658.3885706287565, 2742.053991372973, 2796.8321900686024, 2800.901968431105, 2803.8457884816326, 2598.7791037761717, 2622.1084831157405, 2625.5930330798756, 2630.592258199982, 2632.3813903732703, 2591.326255173659, 2608.9608655870084, 2639.4984952858895, 2651.827631203975, 2652.82756620819, 2621.4626001669803, 2633.8288011279396, 2634.6443230164064, 2653.01838319821, 2661.4144888124774, 14507.70984580313, 14755.483558075523, 14831.70336593262, 14859.182744949023, 14860.331285092088, 7977.202279772035, 8236.546989915552, 8252.958735206346, 8256.628451719687, 8267.090620031791, 5374.946250537523, 5433.157860531624, 5516.338039435253, 5577.3028371453565, 5630.324361076697, 1654.1811803157393, 1680.5882558773058, 1683.360323077235, 1709.1881356355757, 1717.6049508612964, 26069.869650651814, 26281.21156365312, 26315.605265921025, 26319.605205921926, 26378.604320935392, 6597.406233439014, 6821.079154314203, 6827.975803629396, 6986.336298051677, 7062.0, 15770.369185232463, 15772.290246938916, 15774.369025239092, 15790.289436975363, 15793.605159871095, 7655.617219139021, 8263.132371101068, 8452.788680283042, 8463.619137138843, 8472.068072512018, 6206.193194884636, 6482.546221764525, 6656.0, 6657.73369065232, 7551.169371369145, 5141.485851414828, 5269.077911365495, 5322.201669749568, 5331.413544510101, 5339.893202135935, 1539.2303848075962, 1551.7206902757612, 1562.265735104499, 1566.0681894272889, 1567.529741077672, 880.1726377205406, 895.4716717136921, 903.4759839293195, 904.0923651096182, 904.516083895113, 7672.884906726404, 7778.0, 7852.0, 7867.960660196777, 7870.488418252795, 655.7016557466342, 663.6018388966678, 715.6386025057312, 718.4037249083353, 722.5484072454716, 4828.686135401188, 5884.528867783054, 5959.5828292020005, 5980.372060933626, 6097.2381099861095, 20141.0, 20623.896880515502, 20637.690434643493, 21547.569048619254, 22230.666540001912, 20319.593608127754, 20358.18567257323, 20410.795892041187, 20656.277030303867, 20716.378508644764, 10408.47957602117, 10550.788984220273, 10767.676969690921, 10802.351858888493, 10833.349998999927, 8418.705345312885, 8832.470051796912, 8986.955065224633, 9270.907290927003, 9397.530123493934, 18765.6246875059, 18879.716804248223, 18970.43088707341, 19046.7142992858, 19138.138783754486, 19220.327288544835, 19283.710744339125, 19348.32280870163, 19358.612827743647, 19483.02584870779, 11717.296962182296, 12019.0, 12401.13192076582, 12445.875541244472, 12447.439865205906, 14125.929370353082, 14275.500357487437, 14279.64300892486, 14284.928575357057, 14289.499867504588, 11285.37930413835, 11426.085913126764, 11649.88350116488, 11686.415679215836, 11781.05751539892, 11131.443427828739, 11240.943795281299, 11277.210595258253, 11421.657350279505, 11608.941955290169, 10304.711911011123, 10396.58413663445, 10515.15878729715, 10532.26274160832, 10550.261481696514, 7315.817104572428, 7386.667599958122, 7472.887906681295, 7475.738349157971, 7547.433942454228, 6999.1950925644205, 7037.753678621224, 7037.964810175917, 7062.364387205176, 7117.217106118426], "value": 6613.9423202316475, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.98491045268656, 513.3839392728726, 526.5024551798552, 531.4340227657544, 537.0547835808975, 6395.648239346834, 6472.676366181684, 6507.609543427392, 6511.739530418789, 6512.283648798629, 6328.208973878265, 6378.968105159468, 6402.327755585667, 6428.003659432796, 6444.7099880505375, 5264.341957255353, 5284.841454756363, 5294.888073504569, 5311.07056265154, 5343.145096784515, 4903.763516079388, 5081.314022606956, 5276.630635855492, 5316.441773613773, 5328.813491527797, 5086.440491545927, 5087.974560127194, 5103.974480127594, 5109.6678715883345, 5131.486851314858, 5078.365204349457, 5096.719680417576, 5111.1566591512355, 5127.359409903903, 5130.9230361544605, 4467.262901621261, 4559.794809233593, 4565.360849481075, 4568.583739040908, 4573.588377046081, 6291.905621415683, 6299.779507717254, 6307.148534947815, 6308.83286591982, 6339.175907132089, 5840.970795145997, 5862.912056319158, 5872.060470324727, 5872.295324561037, 5874.9118763218585, 6779.15260592426, 6784.253732089466, 6787.457003439713, 6789.219239787439, 6824.556403833735, 4961.875953101166, 4980.452150263468, 5001.374003847218, 5014.84955451337, 5033.370828646419, 6638.4689224861895, 6894.207166175854, 6984.755533556352, 7053.30720627051, 7122.430205583541, 4913.31213630092, 5076.796928122908, 5085.186370180827, 5116.948830511722, 5124.9743751281, 5148.330717006766, 5155.484451554815, 5163.586913047023, 5170.586353091744, 5188.226831315199, 5749.195112684228, 5774.942250577525, 5839.357670656224, 5865.530757539386, 5866.530677545786, 6745.831354216184, 6769.458443324522, 6807.285235050248, 6818.852061600679, 6825.897611535831, 1772.6720556697053, 1794.605186858889, 1810.4924679319238, 1810.7053456778467, 1816.8644597126795, 1184.691980085184, 1230.6123571075082, 1232.1559731583893, 1240.348816871132, 1248.7502499500135, 5978.701064946736, 6002.909956350658, 6005.069214271794, 6042.854786550799, 6050.757969681164, 6822.827385265875, 6878.38094571481, 6910.963355496716, 6926.584404935619, 6945.791626251221, 5909.202257695156, 5915.835028259947, 5951.0, 5955.731992060368, 6033.939660603425, 3622.8188590570894, 3629.6007439181667, 3657.5428071491065, 3668.926621467609, 3694.612065733113, 6315.621062736251, 6612.206535215805, 6630.237522684856, 6646.700898459581, 6716.764913228111, 6951.756688515878, 6955.374016338453, 7035.33164349386, 7064.752733654297, 7080.36276735086, 6564.770233041916, 6577.901331480031, 6662.933370666231, 6667.433268172065, 6695.296993815677, 6794.150731158606, 6828.0, 6845.691943862438, 6905.309469053054, 6979.406750426067, 6820.556663816836, 6841.760538381132, 6845.452363810983, 6878.828029299308, 6898.171984424232, 6610.719301840857, 6628.171478565179, 6690.264070952094, 6733.898991515033, 6757.189137303555, 5100.846974590768, 5113.6477220479, 5122.692638441706, 5129.48705129499, 5131.358580177478, 6601.9009714853355, 6707.088479783433, 6852.5545839522265, 6898.172219333712, 7032.437405007689, 6582.144321238208, 6589.2867854357255, 6628.541720821411, 6659.433948114464, 6683.665816709243, 6547.377999090078, 6581.215800914589, 6614.461263224344, 6620.404163625297, 6687.598744075371, 6481.902771458337, 6543.345665433419, 6561.278259391559, 6591.538592298493, 6612.867742645217, 6762.96618516924, 6795.422389096982, 6808.62552559614, 6810.387065164159, 6815.556988795713, 6555.6066636002, 6564.5076619252795, 6567.868642627216, 6568.80293591193, 6579.440747536513], "value": 5187.853130020686, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [893.1648908270768, 916.3402350307779, 919.4437365393936, 924.269826836799, 927.5269612497626, 17388.0, 18213.817861821393, 18316.175772090253, 19677.704834427495, 20677.276295329666, 20652.277170299043, 20875.582488350225, 21521.892390538025, 21594.568108637817, 21694.674579881314, 921.230772305126, 924.9583768730423, 925.7870689741363, 926.647873807952, 973.5229737428645, 965.9951700241525, 994.5027486256871, 996.2976101848179, 997.9650712225074, 999.0558921818885, 1052.7105046112304, 1053.031211285619, 1055.4933631856713, 1056.7041228456005, 1056.9894301056968, 1043.128987295609, 1043.7651528406125, 1043.88517263101, 1044.5299615173203, 1055.0082922053284, 763.904511936008, 766.31797700047, 767.1024900866, 769.0156599552525, 775.3642013548892, 19436.125374358046, 19489.0, 19805.9009704952, 19850.900745496325, 20327.695084573745, 21204.575908481744, 21266.46833829151, 21779.782202177932, 22267.331980040624, 22281.554368912693, 16361.0, 16442.424515142033, 16667.83332166687, 16687.249073791707, 16815.579610509714, 3748.8313025913626, 3781.6596506314563, 3782.8865134046023, 3783.375743002429, 3784.2620688965662, 130617.34691326578, 131516.0, 132166.0, 133599.33200334033, 133898.0, 16058.919705401631, 16135.596610084607, 16391.590210244838, 16410.83589164117, 16562.50312490603, 30344.54483182754, 30822.53766193509, 30850.69149308523, 31099.689003110132, 31295.217619559695, 7837.725679601187, 8034.718784842619, 8864.157904999014, 9102.907651081912, 9168.954155229314, 4515.932261016087, 4651.209294419957, 4653.930191047137, 4661.510541393172, 4682.719036857799, 5199.376074871039, 5269.710165940834, 5275.340582427197, 5277.736113194325, 5317.707526086025, 1053.5891002509027, 1085.79369919715, 1085.9076978456762, 1088.4068182840213, 1094.8795632480421, 688.3804575881746, 711.1856923822195, 712.1774350625036, 715.8425146467769, 731.2687312687312, 11353.54585816558, 11966.22219555726, 12043.458044388024, 12103.515859365703, 13744.862551374557, 706.6678661029308, 710.2435905760349, 712.4692104382165, 721.9241979592172, 733.6698485681464, 5632.098864181793, 5750.453706897838, 5893.381194974551, 5988.431099045583, 6198.566100372931, 105976.47011764892, 107550.0, 109657.0, 110901.44549277202, 114945.4252728731, 94621.5268923651, 95814.0, 96740.51629741947, 99919.0, 106050.46974765077, 86607.56696216605, 88219.0, 88422.0, 90351.99999999869, 93275.067249328, 12246.326452045223, 12881.033922455666, 12882.03384746129, 12972.627755435835, 12998.610041698765, 40093.0, 41189.382159267036, 41563.337466501, 41740.582594174884, 41895.0, 70041.64979175072, 73548.63225683838, 75113.62443187748, 76056.23943760492, 77240.6137969329, 17788.55528611795, 18616.81383186151, 19180.904095479433, 19480.318188863323, 19490.415287541397, 20875.478113047295, 21478.892605537498, 21993.67009494891, 22212.666809998187, 22440.663390048834, 18735.063246837875, 19024.334148304744, 19124.426267212006, 19217.0, 19744.111514981574, 19151.61696766029, 19278.807211927702, 19541.413757587296, 19710.605787884448, 20196.596068078263, 18388.988605626284, 18638.0, 18723.531911702317, 18932.0, 18964.905175474585, 13693.863061369259, 13998.44006239739, 14424.711505770036, 14466.782998255245, 14547.490837820626, 14634.487792927197, 14770.48303309379, 15036.849631503545, 15076.924615376853, 15117.732340149065], "value": 9386.548623725243, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1488.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 108.0, 97.0, 107.0, 108.0, 110.0, 111.0, 107.0, 110.0, 112.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 123.0, 140.0, 186.0, 116.0, 139.0, 144.0, 116.0, 174.0, 117.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 164.0, 162.0, 164.0, 163.0, 165.0, 166.0, 164.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 173.0, 74.0, 75.0, 75.0, 75.0, 154.0, 74.0], "value": 92.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 134.0, 79.0, 79.0, 83.0, 126.0, 81.0, 80.0, 133.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 117.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 147.0, 147.0, 150.0, 147.0, 147.0, 147.0, 148.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 52.0, 51.0, 53.0, 53.0, 52.0, 53.0, 53.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 76.0, 74.0, 77.0, 75.0, 79.0, 74.0, 75.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 116.0, 129.0, 120.0, 124.0, 129.0, 117.0, 119.0, 129.0, 155.0], "value": 126.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 175.0, 179.0, 179.0, 171.0, 174.0, 173.0, 176.0, 173.0, 181.0], "value": 176.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 64.0, 64.0, 63.0, 65.0, 65.0, 62.0, 63.0], "value": 64.4, "unit": "score"}]}]} 03:04:46 INFO - Return code: 0 03:04:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:04:46 INFO - # TBPL SUCCESS # 03:04:46 INFO - Running post-action listener: _package_coverage_data 03:04:46 INFO - Running post-action listener: _resource_record_post_action 03:04:46 INFO - [mozharness: 2017-09-03 10:04:46.515068Z] Finished run-tests step (success) 03:04:46 INFO - Running post-run listener: _resource_record_post_run 03:04:46 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 390889472; Read time: 48; Write time: 304592 03:04:46 INFO - TinderboxPrint: CPU usage
13.1% 03:04:46 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 48 03:04:46 INFO - TinderboxPrint: I/O write bytes / time
390,889,472 / 304,592 03:04:46 INFO - TinderboxPrint: CPU idle
8,232.5 (86.8%) 03:04:46 INFO - TinderboxPrint: CPU user
1,193.9 (12.6%) 03:04:46 INFO - TinderboxPrint: Swap in / out
0 / 0 03:04:46 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 106610688; Read time: 0; Write time: 110156 03:04:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:04:46 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 284278784; Read time: 48; Write time: 194436 03:04:47 INFO - Running post-run listener: _upload_blobber_files 03:04:47 INFO - Blob upload gear active. 03:04:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:04:47 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:04:47 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:04:47 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:04:47 INFO - (blobuploader) - INFO - Open directory for files ... 03:04:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:04:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:04:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:48 INFO - (blobuploader) - INFO - Done attempting. 03:04:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:04:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:04:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:55 INFO - (blobuploader) - INFO - Done attempting. 03:04:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:04:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:04:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:56 INFO - (blobuploader) - INFO - Done attempting. 03:04:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:04:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:04:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:57 INFO - (blobuploader) - INFO - Done attempting. 03:04:57 INFO - (blobuploader) - INFO - Iteration through files over. 03:04:57 INFO - Return code: 0 03:04:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:04:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:04:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a624beed3c98f2925333dccf08f35c2a27118adc8123b5d837b6250727e32614e5e3321dd49591b954aa82c88fce1e080864755c736ced1675057241c13e0711", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0cfbe7b99731ddf3bf115e22902efc839fa9da892763cedc31d154b50bf37eee00581d69121d776357b11d6a1f616470801d4403cb3861f0a17834ae3aca7c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1488a15daea102c18f28e7d11b4a350547e4f04b4e1cdb8c2b3689dac2889be0db64726ab47fc36dc6dc84a39aea7a5e50135d3e330e5fc06da5bfe52a7d2b75", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e90709a49f357e41d0cabead553016091cb6924c0e20659cd156a8ad65a6189c065595c02dbec957df09a5eaa811f145feed04ebde43d3d66e32a6ed7b22b7de"} 03:04:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:04:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:04:57 INFO - Contents: 03:04:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a624beed3c98f2925333dccf08f35c2a27118adc8123b5d837b6250727e32614e5e3321dd49591b954aa82c88fce1e080864755c736ced1675057241c13e0711", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0cfbe7b99731ddf3bf115e22902efc839fa9da892763cedc31d154b50bf37eee00581d69121d776357b11d6a1f616470801d4403cb3861f0a17834ae3aca7c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1488a15daea102c18f28e7d11b4a350547e4f04b4e1cdb8c2b3689dac2889be0db64726ab47fc36dc6dc84a39aea7a5e50135d3e330e5fc06da5bfe52a7d2b75", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e90709a49f357e41d0cabead553016091cb6924c0e20659cd156a8ad65a6189c065595c02dbec957df09a5eaa811f145feed04ebde43d3d66e32a6ed7b22b7de"} 03:04:57 INFO - Running post-run listener: copy_logs_to_upload_dir 03:04:57 INFO - Copying logs to upload dir... 03:04:57 INFO - mkdir: /builds/slave/test/build/upload/logs 03:04:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.418082 ========= master_lag: 0.39 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-03 03:04:57.535767) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 03:04:57.538888) ========= 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=41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034 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/a624beed3c98f2925333dccf08f35c2a27118adc8123b5d837b6250727e32614e5e3321dd49591b954aa82c88fce1e080864755c736ced1675057241c13e0711", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0cfbe7b99731ddf3bf115e22902efc839fa9da892763cedc31d154b50bf37eee00581d69121d776357b11d6a1f616470801d4403cb3861f0a17834ae3aca7c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1488a15daea102c18f28e7d11b4a350547e4f04b4e1cdb8c2b3689dac2889be0db64726ab47fc36dc6dc84a39aea7a5e50135d3e330e5fc06da5bfe52a7d2b75", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e90709a49f357e41d0cabead553016091cb6924c0e20659cd156a8ad65a6189c065595c02dbec957df09a5eaa811f145feed04ebde43d3d66e32a6ed7b22b7de"} build_url:https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011253 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/a624beed3c98f2925333dccf08f35c2a27118adc8123b5d837b6250727e32614e5e3321dd49591b954aa82c88fce1e080864755c736ced1675057241c13e0711", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0cfbe7b99731ddf3bf115e22902efc839fa9da892763cedc31d154b50bf37eee00581d69121d776357b11d6a1f616470801d4403cb3861f0a17834ae3aca7c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1488a15daea102c18f28e7d11b4a350547e4f04b4e1cdb8c2b3689dac2889be0db64726ab47fc36dc6dc84a39aea7a5e50135d3e330e5fc06da5bfe52a7d2b75", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e90709a49f357e41d0cabead553016091cb6924c0e20659cd156a8ad65a6189c065595c02dbec957df09a5eaa811f145feed04ebde43d3d66e32a6ed7b22b7de"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 03:04:57.571814) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 03:04:57.572130) ========= 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=41a7e32711b8e08598a63237000001d6-1504419240.446159-284375034 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004591 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 03:04:57.621354) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 03:04:57.624671) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 03:04:57.624952) ========= ========= Total master_lag: 0.40 =========