builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-052 starttime: 1504224676.21 results: success (0) revision: 14eea6bedcf3e2f46ea7c908e1ac9b7d256a42f0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:16.207650) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:16.208082) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:16.208355) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:16.318828) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:16.319129) ========= 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=826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-31 17:11:16-- 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% 46.1M=0s 2017-08-31 17:11:16 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119176 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:16.476549) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:16.476880) ========= 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=826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013904 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:16.571670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-31 17:11:16.572029) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 14eea6bedcf3e2f46ea7c908e1ac9b7d256a42f0 --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 14eea6bedcf3e2f46ea7c908e1ac9b7d256a42f0 --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=826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-31 17:11:16,747 truncating revision to first 12 chars 2017-08-31 17:11:16,747 Setting DEBUG logging. 2017-08-31 17:11:16,747 attempt 1/10 2017-08-31 17:11:16,747 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/14eea6bedcf3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-31 17:11:17,261 attempt 1/10 2017-08-31 17:11:17,945 current task status: no status available at this point. state: STARTED 2017-08-31 17:11:17,946 sleeping for 10.00s (attempt 1/10) 2017-08-31 17:11:27,956 attempt 2/10 2017-08-31 17:11:28,293 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-31 17:11:28,520 unpacking tar archive at: mozilla-central-14eea6bedcf3/testing/mozharness/ program finished with exit code 0 elapsedTime=12.092272 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-31 17:11:28.702305) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:28.702635) ========= script_repo_revision: 14eea6bedcf3e2f46ea7c908e1ac9b7d256a42f0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:28.706070) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:28.706332) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-31 17:11:28.718506) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-08-31 17:11:28.718781) ========= /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=826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:11:28 INFO - MultiFileLogger online at 20170831 17:11:28 in /builds/slave/test 17:11:28 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 17:11:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:11:29 INFO - {'append_to_log': False, 17:11:29 INFO - 'base_work_dir': '/builds/slave/test', 17:11:29 INFO - 'blob_upload_branch': 'Firefox', 17:11:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:11:29 INFO - 'branch': 'Firefox', 17:11:29 INFO - 'buildbot_json_path': 'buildprops.json', 17:11:29 INFO - 'code_coverage': False, 17:11:29 INFO - 'config_files': ('talos/linux_config.py',), 17:11:29 INFO - 'default_actions': ('clobber', 17:11:29 INFO - 'read-buildbot-config', 17:11:29 INFO - 'download-and-extract', 17:11:29 INFO - 'populate-webroot', 17:11:29 INFO - 'create-virtualenv', 17:11:29 INFO - 'install', 17:11:29 INFO - 'setup-mitmproxy', 17:11:29 INFO - 'run-tests'), 17:11:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:11:29 INFO - 'disable_ccov_upload': False, 17:11:29 INFO - 'disable_stylo': False, 17:11:29 INFO - 'download_minidump_stackwalk': True, 17:11:29 INFO - 'download_symbols': 'ondemand', 17:11:29 INFO - 'enable_stylo': False, 17:11:29 INFO - 'enable_webrender': False, 17:11:29 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:11:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:11:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:11:29 INFO - 'gecko_profile': False, 17:11:29 INFO - 'gecko_profile_interval': 0, 17:11:29 INFO - 'installer_path': 'installer.exe', 17:11:29 INFO - 'log_level': 'info', 17:11:29 INFO - 'log_name': 'talos', 17:11:29 INFO - 'log_to_console': True, 17:11:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:11:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:11:29 INFO - 'opt_config_files': (), 17:11:29 INFO - 'pip_index': False, 17:11:29 INFO - 'suite': 'dromaeojs-e10s', 17:11:29 INFO - 'system_bits': '32', 17:11:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:11:29 INFO - 'title': 'talos-linux64-ix-052', 17:11:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:11:29 INFO - 'use_talos_json': True, 17:11:29 INFO - 'verify': 'False', 17:11:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:11:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:11:29 INFO - 'work_dir': 'build'} 17:11:29 INFO - [mozharness: 2017-09-01 00:11:29.014397Z] Running clobber step. 17:11:29 INFO - Running pre-action listener: _resource_record_pre_action 17:11:29 INFO - Running main action method: clobber 17:11:29 INFO - rmtree: /builds/slave/test/build 17:11:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:11:30 INFO - Running post-action listener: _resource_record_post_action 17:11:30 INFO - [mozharness: 2017-09-01 00:11:30.029723Z] Finished clobber step (success) 17:11:30 INFO - [mozharness: 2017-09-01 00:11:30.029819Z] Running read-buildbot-config step. 17:11:30 INFO - Running pre-action listener: _resource_record_pre_action 17:11:30 INFO - Running main action method: read_buildbot_config 17:11:30 INFO - Using buildbot properties: 17:11:30 INFO - { 17:11:30 INFO - "project": "", 17:11:30 INFO - "product": "firefox", 17:11:30 INFO - "who": "kwierso@gmail.com", 17:11:30 INFO - "installer_path": "public/build/target.tar.bz2", 17:11:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 17:11:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:11:30 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 17:11:30 INFO - "stage_platform": "linux64-stylo", 17:11:30 INFO - "basedir": "/builds/slave/test", 17:11:30 INFO - "buildnumber": 69, 17:11:30 INFO - "platform": "ubuntu64_hw_stylo", 17:11:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:11:30 INFO - "slavebuilddir": "test", 17:11:30 INFO - "taskId": "G3QsC1lCTESJEUt7OgjwKQ", 17:11:30 INFO - "branch": "mozilla-central", 17:11:30 INFO - "script_repo_revision": "production", 17:11:30 INFO - "revision": "14eea6bedcf3e2f46ea7c908e1ac9b7d256a42f0", 17:11:30 INFO - "slavename": "talos-linux64-ix-052", 17:11:30 INFO - "repo_path": "mozilla-central" 17:11:30 INFO - } 17:11:30 INFO - Finding installer, test and symbols from parent task. 17:11:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3QsC1lCTESJEUt7OgjwKQ'}, attempt #1 17:11:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3QsC1lCTESJEUt7OgjwKQ'}, attempt #1 17:11:30 INFO - Task dependencies: VwskOH_MSgKsTQf61Zi0cg 17:11:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg'}, attempt #1 17:11:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.tar.bz2 17:11:31 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.test_packages.json 17:11:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.crashreporter-symbols.zip 17:11:31 INFO - Running post-action listener: _resource_record_post_action 17:11:31 INFO - [mozharness: 2017-09-01 00:11:31.387054Z] Finished read-buildbot-config step (success) 17:11:31 INFO - [mozharness: 2017-09-01 00:11:31.387270Z] Running download-and-extract step. 17:11:31 INFO - Running pre-action listener: _resource_record_pre_action 17:11:31 INFO - Running main action method: download_and_extract 17:11:31 INFO - mkdir: /builds/slave/test/build/tests 17:11:31 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')]} 17:11:31 INFO - trying https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.test_packages.json 17:11:31 INFO - Downloading https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:11:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:11:31 INFO - Downloaded 989 bytes. 17:11:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:11:31 INFO - Using the following test package requirements: 17:11:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:11:31 INFO - u'common': [u'target.common.tests.zip'], 17:11:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:11:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:11:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:11:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:11:31 INFO - u'mozbase': [u'target.common.tests.zip'], 17:11:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:11:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:11:31 INFO - u'web-platform': [u'target.common.tests.zip', 17:11:31 INFO - u'target.web-platform.tests.tar.gz'], 17:11:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:11:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:11:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.common.tests.zip 17:11:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:11:31 INFO - Fetch https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.common.tests.zip into memory 17:11:33 INFO - Content-Length response header: 54933712 17:11:33 INFO - Bytes received: 54933712 17:11:44 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:11:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.common.tests.zip 17:11:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:11:44 INFO - Fetch https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.common.tests.zip into memory 17:11:45 INFO - Content-Length response header: 54933712 17:11:45 INFO - Bytes received: 54933712 17:11:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.talos.tests.zip 17:11:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:11:58 INFO - Fetch https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.talos.tests.zip into memory 17:11:59 INFO - Content-Length response header: 14496650 17:11:59 INFO - Bytes received: 14496650 17:11:59 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')]} 17:11:59 INFO - trying https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.tar.bz2 17:11:59 INFO - Downloading https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:11:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:12:01 INFO - Downloaded 62603869 bytes. 17:12:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.tar.bz2 17:12:01 INFO - mkdir: /builds/slave/test/properties 17:12:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:12:01 INFO - Writing to file /builds/slave/test/properties/build_url 17:12:01 INFO - Contents: 17:12:01 INFO - build_url:https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.tar.bz2 17:12:01 INFO - Running post-action listener: _resource_record_post_action 17:12:01 INFO - Running post-action listener: find_tests_for_verification 17:12:01 INFO - Running post-action listener: set_extra_try_arguments 17:12:01 INFO - [mozharness: 2017-09-01 00:12:01.171644Z] Finished download-and-extract step (success) 17:12:01 INFO - [mozharness: 2017-09-01 00:12:01.171742Z] Running populate-webroot step. 17:12:01 INFO - Running pre-action listener: _resource_record_pre_action 17:12:01 INFO - Running main action method: populate_webroot 17:12:01 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:12:01 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:12:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:12:01 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:12:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:12:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:12:01 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:12:01 INFO - 'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:12:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:12:01 INFO - 'tests': ['damp', 'tps']}, 17:12:01 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:12:01 INFO - 'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['damp', 'tps']}, 17:12:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:12:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:12:01 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:12:01 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:12:01 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:12:01 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:12:01 INFO - 'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:12:01 INFO - 'other-e10s': {'tests': ['a11yr', 17:12:01 INFO - 'ts_paint', 17:12:01 INFO - 'tpaint', 17:12:01 INFO - 'sessionrestore', 17:12:01 INFO - 'sessionrestore_many_windows', 17:12:01 INFO - 'sessionrestore_no_auto_restore', 17:12:01 INFO - 'tabpaint']}, 17:12:01 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['a11yr', 17:12:01 INFO - 'ts_paint', 17:12:01 INFO - 'tpaint', 17:12:01 INFO - 'sessionrestore', 17:12:01 INFO - 'sessionrestore_many_windows', 17:12:01 INFO - 'sessionrestore_no_auto_restore', 17:12:01 INFO - 'tabpaint']}, 17:12:01 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:12:01 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:12:01 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['bloom_basic_singleton']}, 17:12:01 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['bloom_basic']}, 17:12:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:12:01 INFO - 'tsvgr_opacity', 17:12:01 INFO - 'tart', 17:12:01 INFO - 'tscrollx', 17:12:01 INFO - 'tsvg_static']}, 17:12:01 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['tsvgx', 17:12:01 INFO - 'tsvgr_opacity', 17:12:01 INFO - 'tart', 17:12:01 INFO - 'tscrollx', 17:12:01 INFO - 'tsvg_static']}, 17:12:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:12:01 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:12:01 INFO - 'talos_options': ['--enable-stylo'], 17:12:01 INFO - 'tests': ['tp5o']}, 17:12:01 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:12:01 INFO - 'talos_options': ['--mitmproxy', 17:12:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:12:01 INFO - '--firstNonBlankPaint'], 17:12:01 INFO - 'tests': ['tp6_google', 17:12:01 INFO - 'tp6_youtube', 17:12:01 INFO - 'tp6_amazon', 17:12:01 INFO - 'tp6_facebook']}, 17:12:01 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:12:01 INFO - 'talos_options': ['--enable-stylo', 17:12:01 INFO - '--mitmproxy', 17:12:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:12:01 INFO - '--firstNonBlankPaint'], 17:12:01 INFO - 'tests': ['tp6_google', 17:12:01 INFO - 'tp6_youtube', 17:12:01 INFO - 'tp6_amazon', 17:12:01 INFO - 'tp6_facebook']}, 17:12:01 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:12:01 INFO - 'talos_options': ['--enable-stylo', 17:12:01 INFO - '--stylo-threads=1', 17:12:01 INFO - '--mitmproxy', 17:12:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:12:01 INFO - '--firstNonBlankPaint'], 17:12:01 INFO - 'tests': ['tp6_google', 17:12:01 INFO - 'tp6_youtube', 17:12:01 INFO - 'tp6_amazon', 17:12:01 INFO - 'tp6_facebook']}, 17:12:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:12:01 INFO - 'talos_options': ['--xperf_path', 17:12:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:12:01 INFO - 'tests': ['tp5n']}, 17:12:01 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:12:01 INFO - 'talos_options': ['--enable-stylo', 17:12:01 INFO - '--xperf_path', 17:12:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:12:01 INFO - 'tests': ['tp5n']}}} 17:12:01 INFO - Running post-action listener: _resource_record_post_action 17:12:01 INFO - [mozharness: 2017-09-01 00:12:01.186095Z] Finished populate-webroot step (success) 17:12:01 INFO - [mozharness: 2017-09-01 00:12:01.186184Z] Running create-virtualenv step. 17:12:01 INFO - Running pre-action listener: _resource_record_pre_action 17:12:01 INFO - Running main action method: create_virtualenv 17:12:01 INFO - Creating virtualenv /builds/slave/test/build/venv 17:12:01 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:12:01 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:12:01 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:12:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:12:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:12:01 INFO - Installing distribute.............................................................................................................................................................................................done. 17:12:02 INFO - Installing pip...............done. 17:12:02 INFO - Return code: 0 17:12:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:12:02 INFO - Reading from file tmpfile_stdout 17:12:02 INFO - Output received: 17:12:02 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:12:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:12:02 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')]} 17:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:12:02 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')]} 17:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:12:02 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 0xbad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, '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': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', '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 17:12:02 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 17:12:02 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 17:12:02 INFO - Using env: {'DISPLAY': ':0', 17:12:02 INFO - 'HOME': '/home/cltbld', 17:12:02 INFO - 'LANG': 'en_US.UTF-8', 17:12:02 INFO - 'LANGUAGE': 'en_US:en', 17:12:02 INFO - 'LOGNAME': 'cltbld', 17:12:02 INFO - 'MAIL': '/var/mail/cltbld', 17:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:02 INFO - 'MOZ_NO_REMOTE': '1', 17:12:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:02 INFO - 'NO_EM_RESTART': '1', 17:12:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:02 INFO - 'PWD': '/builds/slave/test', 17:12:02 INFO - 'SHELL': '/bin/bash', 17:12:02 INFO - 'SHLVL': '1', 17:12:02 INFO - 'TERM': 'linux', 17:12:02 INFO - 'TMOUT': '86400', 17:12:02 INFO - 'USER': 'cltbld', 17:12:02 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:02 INFO - '_': '/tools/buildbot/bin/python'} 17:12:03 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:12:03 INFO - Downloading/unpacking pip>=1.5 17:12:03 INFO - Running setup.py egg_info for package pip 17:12:03 INFO - warning: no files found matching 'pip/cacert.pem' 17:12:03 INFO - warning: no files found matching '*.html' under directory 'docs' 17:12:03 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:12:03 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:12:03 INFO - Installing collected packages: pip 17:12:03 INFO - Found existing installation: pip 1.1 17:12:03 INFO - Uninstalling pip: 17:12:03 INFO - Successfully uninstalled pip 17:12:03 INFO - Running setup.py install for pip 17:12:03 INFO - warning: no files found matching 'pip/cacert.pem' 17:12:03 INFO - warning: no files found matching '*.html' under directory 'docs' 17:12:03 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:12:03 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:12:03 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:12:03 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:12:03 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:12:03 INFO - Successfully installed pip 17:12:03 INFO - Cleaning up... 17:12:03 INFO - Return code: 0 17:12:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:12:03 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')]} 17:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:12:03 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')]} 17:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:12:03 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 0xbad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, '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': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', '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 17:12:03 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 17:12:03 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 17:12:03 INFO - Using env: {'DISPLAY': ':0', 17:12:03 INFO - 'HOME': '/home/cltbld', 17:12:03 INFO - 'LANG': 'en_US.UTF-8', 17:12:03 INFO - 'LANGUAGE': 'en_US:en', 17:12:03 INFO - 'LOGNAME': 'cltbld', 17:12:03 INFO - 'MAIL': '/var/mail/cltbld', 17:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:03 INFO - 'MOZ_NO_REMOTE': '1', 17:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:03 INFO - 'NO_EM_RESTART': '1', 17:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:03 INFO - 'PWD': '/builds/slave/test', 17:12:03 INFO - 'SHELL': '/bin/bash', 17:12:03 INFO - 'SHLVL': '1', 17:12:03 INFO - 'TERM': 'linux', 17:12:03 INFO - 'TMOUT': '86400', 17:12:03 INFO - 'USER': 'cltbld', 17:12:03 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:03 INFO - '_': '/tools/buildbot/bin/python'} 17:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:12:03 INFO - Downloading/unpacking psutil>=3.1.1 17:12:03 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 17:12:03 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 17:12:03 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 17:12:03 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 17:12:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:12:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:12:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:12:04 INFO - Installing collected packages: psutil 17:12:04 INFO - Running setup.py install for psutil 17:12:04 INFO - building 'psutil._psutil_linux' extension 17:12:04 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 17:12:05 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 17:12:05 INFO - building 'psutil._psutil_posix' extension 17:12:05 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 17:12:05 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 17:12:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:12:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:12:05 INFO - Successfully installed psutil 17:12:05 INFO - Cleaning up... 17:12:05 INFO - Return code: 0 17:12:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:12:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:12:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:12:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, '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': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', '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 17:12:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:12:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:12:05 INFO - Using env: {'DISPLAY': ':0', 17:12:05 INFO - 'HOME': '/home/cltbld', 17:12:05 INFO - 'LANG': 'en_US.UTF-8', 17:12:05 INFO - 'LANGUAGE': 'en_US:en', 17:12:05 INFO - 'LOGNAME': 'cltbld', 17:12:05 INFO - 'MAIL': '/var/mail/cltbld', 17:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:05 INFO - 'MOZ_NO_REMOTE': '1', 17:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:05 INFO - 'NO_EM_RESTART': '1', 17:12:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:05 INFO - 'PWD': '/builds/slave/test', 17:12:05 INFO - 'SHELL': '/bin/bash', 17:12:05 INFO - 'SHLVL': '1', 17:12:05 INFO - 'TERM': 'linux', 17:12:05 INFO - 'TMOUT': '86400', 17:12:05 INFO - 'USER': 'cltbld', 17:12:05 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:05 INFO - '_': '/tools/buildbot/bin/python'} 17:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:12:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:12:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:12:06 INFO - Installing collected packages: mozsystemmonitor 17:12:06 INFO - Running setup.py install for mozsystemmonitor 17:12:06 INFO - Successfully installed mozsystemmonitor 17:12:06 INFO - Cleaning up... 17:12:06 INFO - Return code: 0 17:12:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:12:06 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')]} 17:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:12:06 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')]} 17:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:12:06 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 0xbad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, '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': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', '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 17:12:06 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 17:12:06 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 17:12:06 INFO - Using env: {'DISPLAY': ':0', 17:12:06 INFO - 'HOME': '/home/cltbld', 17:12:06 INFO - 'LANG': 'en_US.UTF-8', 17:12:06 INFO - 'LANGUAGE': 'en_US:en', 17:12:06 INFO - 'LOGNAME': 'cltbld', 17:12:06 INFO - 'MAIL': '/var/mail/cltbld', 17:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:06 INFO - 'MOZ_NO_REMOTE': '1', 17:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:06 INFO - 'NO_EM_RESTART': '1', 17:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:06 INFO - 'PWD': '/builds/slave/test', 17:12:06 INFO - 'SHELL': '/bin/bash', 17:12:06 INFO - 'SHLVL': '1', 17:12:06 INFO - 'TERM': 'linux', 17:12:06 INFO - 'TMOUT': '86400', 17:12:06 INFO - 'USER': 'cltbld', 17:12:06 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:06 INFO - '_': '/tools/buildbot/bin/python'} 17:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:12:07 INFO - Downloading/unpacking jsonschema==2.5.1 17:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:12:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:08 INFO - Downloading functools32-3.2.3-2.tar.gz 17:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:12:08 INFO - warning: no files found matching '*.txt' 17:12:08 INFO - no previously-included directories found matching 'build' 17:12:08 INFO - no previously-included directories found matching 'dist' 17:12:08 INFO - no previously-included directories found matching '.git*' 17:12:08 INFO - Installing collected packages: jsonschema, functools32 17:12:08 INFO - Running setup.py install for functools32 17:12:08 INFO - warning: no files found matching '*.txt' 17:12:08 INFO - no previously-included directories found matching 'build' 17:12:08 INFO - no previously-included directories found matching 'dist' 17:12:08 INFO - no previously-included directories found matching '.git*' 17:12:08 INFO - Successfully installed jsonschema functools32 17:12:08 INFO - Cleaning up... 17:12:08 INFO - Return code: 0 17:12:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:12: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')]} 17:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:12: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')]} 17:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:12:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xbad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, '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': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', '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 17:12:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:12:08 INFO - Using env: {'DISPLAY': ':0', 17:12:08 INFO - 'HOME': '/home/cltbld', 17:12:08 INFO - 'LANG': 'en_US.UTF-8', 17:12:08 INFO - 'LANGUAGE': 'en_US:en', 17:12:08 INFO - 'LOGNAME': 'cltbld', 17:12:08 INFO - 'MAIL': '/var/mail/cltbld', 17:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:08 INFO - 'MOZ_NO_REMOTE': '1', 17:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:08 INFO - 'NO_EM_RESTART': '1', 17:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:08 INFO - 'PWD': '/builds/slave/test', 17:12:08 INFO - 'SHELL': '/bin/bash', 17:12:08 INFO - 'SHLVL': '1', 17:12:08 INFO - 'TERM': 'linux', 17:12:08 INFO - 'TMOUT': '86400', 17:12:08 INFO - 'USER': 'cltbld', 17:12:08 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:08 INFO - '_': '/tools/buildbot/bin/python'} 17:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:12:08 INFO - Cleaning up... 17:12:08 INFO - Return code: 0 17:12:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:12: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')]} 17:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:12: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')]} 17:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:12:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xbad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, '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': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', '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 17:12:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:12:08 INFO - Using env: {'DISPLAY': ':0', 17:12:08 INFO - 'HOME': '/home/cltbld', 17:12:08 INFO - 'LANG': 'en_US.UTF-8', 17:12:08 INFO - 'LANGUAGE': 'en_US:en', 17:12:08 INFO - 'LOGNAME': 'cltbld', 17:12:08 INFO - 'MAIL': '/var/mail/cltbld', 17:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:08 INFO - 'MOZ_NO_REMOTE': '1', 17:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:08 INFO - 'NO_EM_RESTART': '1', 17:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:08 INFO - 'PWD': '/builds/slave/test', 17:12:08 INFO - 'SHELL': '/bin/bash', 17:12:08 INFO - 'SHLVL': '1', 17:12:08 INFO - 'TERM': 'linux', 17:12:08 INFO - 'TMOUT': '86400', 17:12:08 INFO - 'USER': 'cltbld', 17:12:08 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:08 INFO - '_': '/tools/buildbot/bin/python'} 17:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:12:08 INFO - Downloading/unpacking blobuploader==1.2.4 17:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:10 INFO - Downloading blobuploader-1.2.4.tar.gz 17:12:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:12:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:12:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:10 INFO - Downloading docopt-0.6.1.tar.gz 17:12:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:12:10 INFO - Installing collected packages: blobuploader, requests, docopt 17:12:10 INFO - Running setup.py install for blobuploader 17:12:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:12:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:12:10 INFO - Running setup.py install for requests 17:12:11 INFO - Running setup.py install for docopt 17:12:11 INFO - Successfully installed blobuploader requests docopt 17:12:11 INFO - Cleaning up... 17:12:11 INFO - Return code: 0 17:12:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:12:11 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 0xbad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, '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': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', '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 17:12:11 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 17:12:11 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 17:12:11 INFO - Using env: {'DISPLAY': ':0', 17:12:11 INFO - 'HOME': '/home/cltbld', 17:12:11 INFO - 'LANG': 'en_US.UTF-8', 17:12:11 INFO - 'LANGUAGE': 'en_US:en', 17:12:11 INFO - 'LOGNAME': 'cltbld', 17:12:11 INFO - 'MAIL': '/var/mail/cltbld', 17:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:11 INFO - 'MOZ_NO_REMOTE': '1', 17:12:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:11 INFO - 'NO_EM_RESTART': '1', 17:12:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:11 INFO - 'PWD': '/builds/slave/test', 17:12:11 INFO - 'SHELL': '/bin/bash', 17:12:11 INFO - 'SHLVL': '1', 17:12:11 INFO - 'TERM': 'linux', 17:12:11 INFO - 'TMOUT': '86400', 17:12:11 INFO - 'USER': 'cltbld', 17:12:11 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:11 INFO - '_': '/tools/buildbot/bin/python'} 17:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:12:11 INFO - Running setup.py (path:/tmp/pip-TeEvfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:12:11 INFO - Running setup.py (path:/tmp/pip-fV7cZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:12:11 INFO - Running setup.py (path:/tmp/pip-12o6x4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:12:11 INFO - Running setup.py (path:/tmp/pip-F0Zgwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:12:11 INFO - Running setup.py (path:/tmp/pip-yQ7SOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:12:11 INFO - Running setup.py (path:/tmp/pip-Yhrgfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:12:11 INFO - Running setup.py (path:/tmp/pip-RFYjKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:12:11 INFO - Running setup.py (path:/tmp/pip-ntThDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:12:12 INFO - Running setup.py (path:/tmp/pip-UmDP3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:12:12 INFO - Running setup.py (path:/tmp/pip-3rkXbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:12:12 INFO - Running setup.py (path:/tmp/pip-iUDbCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:12:12 INFO - Running setup.py (path:/tmp/pip-di2wS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:12:12 INFO - Running setup.py (path:/tmp/pip-onbohP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:12:12 INFO - Running setup.py (path:/tmp/pip-Hmxq69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:12:12 INFO - Running setup.py (path:/tmp/pip-FnnlaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:12:12 INFO - Running setup.py (path:/tmp/pip-wHNBr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:12:12 INFO - Running setup.py (path:/tmp/pip-fwnkPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:12:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:12:12 INFO - Running setup.py install for manifestparser 17:12:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:12:12 INFO - Running setup.py install for mozcrash 17:12:12 INFO - Running setup.py install for mozdebug 17:12:12 INFO - Running setup.py install for mozdevice 17:12:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Running setup.py install for mozfile 17:12:13 INFO - Running setup.py install for mozhttpd 17:12:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Running setup.py install for mozinfo 17:12:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Running setup.py install for mozInstall 17:12:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Running setup.py install for mozleak 17:12:13 INFO - Running setup.py install for mozlog 17:12:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Running setup.py install for moznetwork 17:12:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Running setup.py install for mozprocess 17:12:13 INFO - Running setup.py install for mozprofile 17:12:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:12:13 INFO - Running setup.py install for mozrunner 17:12:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:12:14 INFO - Running setup.py install for mozscreenshot 17:12:14 INFO - Running setup.py install for moztest 17:12:14 INFO - Running setup.py install for mozversion 17:12:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:12:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:12:14 INFO - Cleaning up... 17:12:14 INFO - Return code: 0 17:12:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:12:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:12:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:12:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:12:14 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 0xbad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, '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': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', '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 17:12:14 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 17:12:14 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 17:12:14 INFO - Using env: {'DISPLAY': ':0', 17:12:14 INFO - 'HOME': '/home/cltbld', 17:12:14 INFO - 'LANG': 'en_US.UTF-8', 17:12:14 INFO - 'LANGUAGE': 'en_US:en', 17:12:14 INFO - 'LOGNAME': 'cltbld', 17:12:14 INFO - 'MAIL': '/var/mail/cltbld', 17:12:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:14 INFO - 'MOZ_NO_REMOTE': '1', 17:12:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:14 INFO - 'NO_EM_RESTART': '1', 17:12:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:14 INFO - 'PWD': '/builds/slave/test', 17:12:14 INFO - 'SHELL': '/bin/bash', 17:12:14 INFO - 'SHLVL': '1', 17:12:14 INFO - 'TERM': 'linux', 17:12:14 INFO - 'TMOUT': '86400', 17:12:14 INFO - 'USER': 'cltbld', 17:12:14 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:14 INFO - '_': '/tools/buildbot/bin/python'} 17:12:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:12:14 INFO - Running setup.py (path:/tmp/pip-3PxVBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:12:14 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)) 17:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:12:14 INFO - Running setup.py (path:/tmp/pip-Hf9FXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:12:14 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)) 17:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:12:14 INFO - Running setup.py (path:/tmp/pip-DtixXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:12:14 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)) 17:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:12:14 INFO - Running setup.py (path:/tmp/pip-hsv9YL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:12:14 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)) 17:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:12:14 INFO - Running setup.py (path:/tmp/pip-IaHxGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:12:14 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)) 17:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:12:14 INFO - Running setup.py (path:/tmp/pip-WG23T4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:12:15 INFO - Running setup.py (path:/tmp/pip-9dMiwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:12:15 INFO - Running setup.py (path:/tmp/pip-BeEXNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:12:15 INFO - Running setup.py (path:/tmp/pip-QAVlVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:12:15 INFO - Running setup.py (path:/tmp/pip-801Rd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:12:15 INFO - Running setup.py (path:/tmp/pip-GnO2uC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:12:15 INFO - Running setup.py (path:/tmp/pip-n807KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:12:15 INFO - Running setup.py (path:/tmp/pip-uk_CDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:12:15 INFO - Running setup.py (path:/tmp/pip-HgJLkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:12:15 INFO - Running setup.py (path:/tmp/pip-1ApLOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:12:15 INFO - Running setup.py (path:/tmp/pip-Einv04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:12:15 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)) 17:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:12:15 INFO - Running setup.py (path:/tmp/pip-8xhbik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:12:15 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)) 17:12:15 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)) 17:12:15 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)) 17:12:15 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)) 17:12:15 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)) 17:12:15 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)) 17:12:15 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)) 17:12:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:16 INFO - Downloading blessings-1.6.tar.gz 17:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:12:17 INFO - Installing collected packages: blessings 17:12:17 INFO - Running setup.py install for blessings 17:12:17 INFO - Successfully installed blessings 17:12:17 INFO - Cleaning up... 17:12:17 INFO - Return code: 0 17:12:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:12:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:12:17 INFO - Reading from file tmpfile_stdout 17:12:17 INFO - Current package versions: 17:12:17 INFO - argparse == 1.2.1 17:12:17 INFO - blessings == 1.6 17:12:17 INFO - blobuploader == 1.2.4 17:12:17 INFO - docopt == 0.6.1 17:12:17 INFO - functools32 == 3.2.3-2 17:12:17 INFO - jsonschema == 2.5.1 17:12:17 INFO - manifestparser == 1.1 17:12:17 INFO - mozInstall == 1.13 17:12:17 INFO - mozcrash == 1.0 17:12:17 INFO - mozdebug == 0.1 17:12:17 INFO - mozdevice == 0.51 17:12:17 INFO - mozfile == 1.2 17:12:17 INFO - mozhttpd == 0.7 17:12:17 INFO - mozinfo == 0.10 17:12:17 INFO - mozleak == 0.1 17:12:17 INFO - mozlog == 3.5 17:12:17 INFO - moznetwork == 0.27 17:12:17 INFO - mozprocess == 0.25 17:12:17 INFO - mozprofile == 0.28 17:12:17 INFO - mozrunner == 6.13 17:12:17 INFO - mozscreenshot == 0.1 17:12:17 INFO - mozsystemmonitor == 0.3 17:12:17 INFO - moztest == 0.8 17:12:17 INFO - mozversion == 1.4 17:12:17 INFO - psutil == 3.1.1 17:12:17 INFO - requests == 1.2.3 17:12:17 INFO - wsgiref == 0.1.2 17:12:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:12:17 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 0xbad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, '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': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', '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 17:12:17 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 17:12:17 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 17:12:17 INFO - Using env: {'DISPLAY': ':0', 17:12:17 INFO - 'HOME': '/home/cltbld', 17:12:17 INFO - 'LANG': 'en_US.UTF-8', 17:12:17 INFO - 'LANGUAGE': 'en_US:en', 17:12:17 INFO - 'LOGNAME': 'cltbld', 17:12:17 INFO - 'MAIL': '/var/mail/cltbld', 17:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:17 INFO - 'MOZ_NO_REMOTE': '1', 17:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:17 INFO - 'NO_EM_RESTART': '1', 17:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:17 INFO - 'PWD': '/builds/slave/test', 17:12:17 INFO - 'SHELL': '/bin/bash', 17:12:17 INFO - 'SHLVL': '1', 17:12:17 INFO - 'TERM': 'linux', 17:12:17 INFO - 'TMOUT': '86400', 17:12:17 INFO - 'USER': 'cltbld', 17:12:17 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:17 INFO - '_': '/tools/buildbot/bin/python'} 17:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:12:17 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)) 17:12:17 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)) 17:12:17 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)) 17:12:17 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)) 17:12:17 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)) 17:12:17 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)) 17:12:17 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)) 17:12:17 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)) 17:12:17 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)) 17:12:17 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:12:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:12:18 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)) 17:12:18 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)) 17:12:18 INFO - Installing collected packages: simplejson 17:12:18 INFO - Running setup.py install for simplejson 17:12:18 INFO - building 'simplejson._speedups' extension 17:12:18 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 17:12:19 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 17:12:19 INFO - Successfully installed simplejson 17:12:19 INFO - Cleaning up... 17:12:19 INFO - Return code: 0 17:12:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:12:19 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 0xbad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, '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': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', '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 17:12:19 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 17:12:19 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 17:12:19 INFO - Using env: {'DISPLAY': ':0', 17:12:19 INFO - 'HOME': '/home/cltbld', 17:12:19 INFO - 'LANG': 'en_US.UTF-8', 17:12:19 INFO - 'LANGUAGE': 'en_US:en', 17:12:19 INFO - 'LOGNAME': 'cltbld', 17:12:19 INFO - 'MAIL': '/var/mail/cltbld', 17:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:19 INFO - 'MOZ_NO_REMOTE': '1', 17:12:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:19 INFO - 'NO_EM_RESTART': '1', 17:12:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:19 INFO - 'PWD': '/builds/slave/test', 17:12:19 INFO - 'SHELL': '/bin/bash', 17:12:19 INFO - 'SHLVL': '1', 17:12:19 INFO - 'TERM': 'linux', 17:12:19 INFO - 'TMOUT': '86400', 17:12:19 INFO - 'USER': 'cltbld', 17:12:19 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:19 INFO - '_': '/tools/buildbot/bin/python'} 17:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:12:20 INFO - Cleaning up... 17:12:20 INFO - Return code: 0 17:12:20 INFO - Running post-action listener: _resource_record_post_action 17:12:20 INFO - Running post-action listener: _start_resource_monitoring 17:12:20 INFO - Starting resource monitoring. 17:12:20 INFO - [mozharness: 2017-09-01 00:12:20.087288Z] Finished create-virtualenv step (success) 17:12:20 INFO - [mozharness: 2017-09-01 00:12:20.087796Z] Running install step. 17:12:20 INFO - Running pre-action listener: _resource_record_pre_action 17:12:20 INFO - Running main action method: install 17:12:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:12:20 INFO - Reading from file tmpfile_stdout 17:12:20 INFO - Detecting whether we're running mozinstall >=1.0... 17:12:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:12:20 INFO - Reading from file tmpfile_stdout 17:12:20 INFO - Output received: 17:12:20 INFO - Usage: mozinstall [options] installer 17:12:20 INFO - Options: 17:12:20 INFO - -h, --help show this help message and exit 17:12:20 INFO - -d DEST, --destination=DEST 17:12:20 INFO - Directory to install application into. [default: 17:12:20 INFO - "/builds/slave/test"] 17:12:20 INFO - --app=APP Application being installed. [default: firefox] 17:12:20 INFO - mkdir: /builds/slave/test/build/application 17:12:20 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'] 17:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:12:36 INFO - Reading from file tmpfile_stdout 17:12:36 INFO - Output received: 17:12:36 INFO - /builds/slave/test/build/application/firefox/firefox 17:12:36 INFO - Running post-action listener: _resource_record_post_action 17:12:36 INFO - [mozharness: 2017-09-01 00:12:36.877345Z] Finished install step (success) 17:12:36 INFO - [mozharness: 2017-09-01 00:12:36.877597Z] Running setup-mitmproxy step. 17:12:36 INFO - Running pre-action listener: _resource_record_pre_action 17:12:36 INFO - Running main action method: setup_mitmproxy 17:12:36 INFO - Skipping: mitmproxy is not required 17:12:36 INFO - Running post-action listener: _resource_record_post_action 17:12:36 INFO - [mozharness: 2017-09-01 00:12:36.878710Z] Finished setup-mitmproxy step (success) 17:12:36 INFO - [mozharness: 2017-09-01 00:12:36.878930Z] Running run-tests step. 17:12:36 INFO - Running pre-action listener: _resource_record_pre_action 17:12:36 INFO - Running pre-action listener: _set_gcov_prefix 17:12:36 INFO - Running main action method: run_tests 17:12:36 WARNING - Try message not found. 17:12:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:12:36 INFO - Python 2.7.3 17:12:36 INFO - Return code: 0 17:12:36 INFO - grabbing minidump binary from tooltool 17:12:36 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')]} 17:12:36 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 0xf1d090>, '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 0xf1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf17f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:12:36 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 17:12:36 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 17:12:36 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 17:12:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:12:37 INFO - Return code: 0 17:12:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:12:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:12:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:12:37 INFO - ENV: RUST_BACKTRACE is now full 17:12:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:12:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:12:37 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/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 17:12:37 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/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 17:12:37 INFO - Using env: {'DISPLAY': ':0', 17:12:37 INFO - 'HOME': '/home/cltbld', 17:12:37 INFO - 'LANG': 'en_US.UTF-8', 17:12:37 INFO - 'LANGUAGE': 'en_US:en', 17:12:37 INFO - 'LOGNAME': 'cltbld', 17:12:37 INFO - 'MAIL': '/var/mail/cltbld', 17:12:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:12:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:37 INFO - 'MOZ_NO_REMOTE': '1', 17:12:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:37 INFO - 'NO_EM_RESTART': '1', 17:12:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:37 INFO - 'PWD': '/builds/slave/test', 17:12:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:12:37 INFO - 'RUST_BACKTRACE': 'full', 17:12:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:12:37 INFO - 'SHELL': '/bin/bash', 17:12:37 INFO - 'SHLVL': '1', 17:12:37 INFO - 'STYLO_FORCE_ENABLED': '1', 17:12:37 INFO - 'TERM': 'linux', 17:12:37 INFO - 'TMOUT': '86400', 17:12:37 INFO - 'USER': 'cltbld', 17:12:37 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:37 INFO - '_': '/tools/buildbot/bin/python'} 17:12:37 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/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 17:12:37 INFO - mozversion application_buildid: 20170831232651 17:12:37 INFO - mozversion application_changeset: 14eea6bedcf3e2f46ea7c908e1ac9b7d256a42f0 17:12:37 INFO - mozversion application_display_name: Nightly 17:12:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:12:37 INFO - mozversion application_name: Firefox 17:12:37 INFO - mozversion application_remotingname: firefox 17:12:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:12:37 INFO - mozversion application_vendor: Mozilla 17:12:37 INFO - mozversion application_version: 57.0a1 17:12:37 INFO - mozversion platform_buildid: 20170831232651 17:12:37 INFO - mozversion platform_changeset: 14eea6bedcf3e2f46ea7c908e1ac9b7d256a42f0 17:12:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:12:37 INFO - mozversion platform_version: 57.0a1 17:12:37 INFO - using testdate: 1504224757 17:12:37 INFO - actual date: 1504224757 17:12:37 INFO - starting webserver on 'localhost:50831' 17:12:37 INFO - SUITE-START | Running 2 tests 17:12:37 INFO - TEST-START | dromaeo_css 17:12:37 INFO - Initialising browser for dromaeo_css test... 17:12:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50831/getInfo.html -profile /tmp/tmpSfK9r1/profile 17:12:37 INFO - TEST-INFO | started process 9111 (/builds/slave/test/build/application/firefox/firefox http://localhost:50831/getInfo.html) 17:12:43 INFO - TEST-INFO | 9111: exit 0 17:12:43 INFO - Browser initialized. 17:12:43 INFO - Running cycle 1/1 for dromaeo_css test... 17:12:43 INFO - Using env: {'DISPLAY': ':0', 17:12:43 INFO - 'HOME': '/home/cltbld', 17:12:43 INFO - 'JSGC_DISABLE_POISONING': '1', 17:12:43 INFO - 'LANG': 'en_US.UTF-8', 17:12:43 INFO - 'LANGUAGE': 'en_US:en', 17:12:43 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:12:43 INFO - 'LOGNAME': 'cltbld', 17:12:43 INFO - 'MAIL': '/var/mail/cltbld', 17:12:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:12:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:12:43 INFO - 'MOZ_CRASHREPORTER': '1', 17:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:12:43 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:12:43 INFO - 'MOZ_NO_REMOTE': '1', 17:12:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:12:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:12:43 INFO - 'NO_EM_RESTART': '1', 17:12:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:12:43 INFO - 'PWD': '/builds/slave/test', 17:12:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:12:43 INFO - 'RUST_BACKTRACE': 'full', 17:12:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:12:43 INFO - 'SHELL': '/bin/bash', 17:12:43 INFO - 'SHLVL': '1', 17:12:43 INFO - 'STYLO_FORCE_ENABLED': '1', 17:12:43 INFO - 'TERM': 'linux', 17:12:43 INFO - 'TMOUT': '86400', 17:12:43 INFO - 'USER': 'cltbld', 17:12:43 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:12:43 INFO - '_': '/tools/buildbot/bin/python'} 17:12:43 INFO - TEST-INFO | started process 9306 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSfK9r1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:12:44 INFO - PID 9306 | 17:12:44 INFO - PID 9306 | (/builds/slave/test/build/application/firefox/firefox:9358): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:12:44 INFO - PID 9306 | 17:12:54 INFO - PID 9306 | RSS: Main: 171233280 17:12:54 INFO - PID 9306 | 17:15:53 INFO - PID 9306 | Cycle 1(1): loaded http://localhost:50831/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50831/tests/dromaeo/cssquery-ext.html) 17:15:54 INFO - PID 9306 | RSS: Main: 174321664 17:15:54 INFO - PID 9306 | 17:18:56 INFO - PID 9306 | Cycle 1(1): loaded http://localhost:50831/tests/dromaeo/cssquery-ext.html (next: http://localhost:50831/tests/dromaeo/cssquery-jquery.html) 17:18:57 INFO - PID 9306 | RSS: Main: 170352640 17:18:57 INFO - PID 9306 | 17:21:58 INFO - PID 9306 | Cycle 1(1): loaded http://localhost:50831/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50831/tests/dromaeo/cssquery-mootools.html) 17:21:59 INFO - PID 9306 | RSS: Main: 173572096 17:21:59 INFO - PID 9306 | 17:25:01 INFO - PID 9306 | Cycle 1(1): loaded http://localhost:50831/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50831/tests/dromaeo/cssquery-prototype.html) 17:25:01 INFO - PID 9306 | RSS: Main: 171053056 17:25:01 INFO - PID 9306 | 17:28:05 INFO - PID 9306 | Cycle 1(1): loaded http://localhost:50831/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50831/tests/dromaeo/cssquery-yui.html) 17:28:06 INFO - PID 9306 | RSS: Main: 172556288 17:28:06 INFO - PID 9306 | 17:31:07 INFO - PID 9306 | Cycle 1(1): loaded http://localhost:50831/tests/dromaeo/cssquery-yui.html (next: http://localhost:50831/tests/dromaeo/cssquery-dojo.html) 17:31:08 INFO - PID 9306 | RSS: Main: 175038464 17:31:08 INFO - PID 9306 | 17:31:08 INFO - PID 9306 | __start_tp_report 17:31:08 INFO - PID 9306 | _x_x_mozilla_page_load 17:31:08 INFO - PID 9306 | _x_x_mozilla_page_load_details 17:31:08 INFO - PID 9306 | |i|pagename|runs| 17:31:08 INFO - PID 9306 | |0;dojo.html;3322.235885746278;3332.90001299961;3334.983325083375;3354.2452948086698;3364.7981121132734;6280.434760871521;6425.48596112311;6433.935660643398;6454.0641606966965;6511.772087976922;6317.052442133682;6385.712642931067;6392.968035159829;6396.328385519511;6420.390062944013;5876.706164691748;5878.323992740826;5899.174115623816;5916.88166236675;5937.4656280934705;5888.470037696607;5899.852503687399;5910.645361278315;5911.556633252501;5917.289925208981;5928.40715928408;5962.403759624046;6026.9095963560585;6045.03279475284;6071.301800292957;5846.590738648296;5929.73316200772;6001.339852616209;6021.307549631804;6061.272647282311;5756.395578464243;5762.366139724627;5863.061910094364;5889.204957330749;5909.113632955048;6150;6251.499880009588;6301.275353334334;6328.303886572473;6342.334054924213;6717.496187785936;6743.629100399476;6832.521723479358;6852.246252912176;6862.176538815374;6782.152230971129;6825.044493770876;6906.585604863724;6916.031755554226;6928.584284942919;6378.649174295412;6407.8398040048905;6416.518761092937;6428.8071357859335;6490.513211509156;7114.110736157981;7117.501774875813;7122.465815063891;7123.857522849514;7153.535020223669;6721.1598550181225;6740.29226931175;6808.863822723518;6823.283555226729;6826.658667066627;6847.1783385994;6866.313368663094;6866.828329291808;6948.409385202213;6955.130608673916;6633.668316584151;6785.287544807823;6802;6807.047013418125;6812.250652428229;6599.73601055962;6617.073609694647;6622.701978410983;6624.668766561749;6650.567713098633;5221.32122824038;5221.843344699665;5224.843254702365;5225.216217567396;5231.293775340357;4636.4436267647425;4637.072585482917;4638.0260145369175;4722.268048452495;4830.396200474941;6008.639481631116;6059.697015149225;6080.0879868019265;6084.726187321581;6088.452039316394;6815.625140617214;6903.275156108637;6904.1024666794;6910.44716422685;6914.412274956685;5924.496417804448;5931.555133364928;6042.486388657014;6043.456088951927;6113.96943015291;5946.137810017523;5950.9107363389585;6004.729787159588;6024.367441418675;6025.367336429612;6488.059231411513;6550.672466376662;6653.9667301663185;6716.597004179667;6716.597004179667;6971.372576468142;6989.965050174716;7000.7549735759;7029.507934444744;7032.367086962198;6695.966520167368;6708.161479815023;6745.898811517733;6781.728730850711;6797.25230224665;6813.080234168324;6819.863602728017;6844.760433385007;6937.653117344012;6943.340382663639;6669.83325416849;6778.8983165251575;6891.104156459628;6968.860622787617;7087.22040575526;6619.536632435684;6691.866162676816;6711.865762684816;6713.832154196184;6770.6614669265355;6707.429868461236;6786.69459874297;6801.795946121624;6806.047153398627;6807.829804254933;6619.50353723463;6632.071509988702;6643.60138391698;6731.932680673326;6742.25835158142;6588.472922166311;6734.966325168343;6739.123913891162;6739.898901516383;6832.863342733018;6471.223453185459;6488.870222595616;6547.083408322934;6616.073749674953;6627.502937279627 17:31:08 INFO - PID 9306 | |1;ext.html;798.6366203377464;822.3503432288493;850.6299759604573;882.1178821178823;883.4831623500248;39834;40488.19023619526;40558.18883622326;40577.79711101444;40683;36189.27621447576;36354.454683179705;36395.81802090986;36396.81801590995;36441.724539641116;6760.526763126571;6848.794536163923;6855.520113592051;6858.177018757731;6870.038194652728;6804.421624161952;6865.691043903012;6874.656267186646;6879.27767584404;6903.930960690379;6851.109355783766;7469.21573234808;7601.847963040764;7613.467057305991;7616.581088040156;7522.774316770506;7564.092308922937;7565.886511702329;7565.886511702329;7569.356604688608;7382.151052628963;7393.482456228066;7474.551526908403;7493.700251989914;7494.213107623676;36246;36494;36542.45186322207;36588.26823463516;36619.81690091533;3439.105832483547;3450.723942084627;3455.6371580984137;3456.4296891012955;3461.152017755641;30044.699553004408;30162.84918575415;30178;30216.54675179874;30265.546016809767;5134.922976155361;5144.0740666679985;5148.459411761749;5173.430922598549;5173.611979101583;185649;185759;186893.99999999863;187018;187460;47759.52240477551;47789;48029.27956080592;48051.75974120107;48054.79863003383;49899;49919.25121123186;49934.504584678725;49976.25035624469;50010.49989500131;14442.638934026734;14596.051256668283;14609.269536523343;14610.707785844224;14658.19379934114;11938.164328496921;11938.164328497096;11963.940180299043;11966.743491933395;11978.101642376856;14115.78826317606;14187.14877107377;14192.148471091768;14201.715965680629;14335.641608959859;5710.114932185435;5747.080467125324;5747.3677895431465;5760.798372056958;5790.36306006339;988.8714467119372;1011.3476807459148;1019.5870672377699;1026.861373714539;1030.5156576409076;22564.66153007706;22571.322860314216;22607.660885086738;22708.65937010946;22790.202342918248;1090.1551297744154;1108.3571528513444;1111.349860331699;1116.0680831505783;1131.2816361610437;12121.333326667118;12261.570845020382;12279.447424865903;12298.877011229773;12303.384830758427;229185;230575;230960;230983;230989;50309.748451258245;50479.747601261755;51580.22629660558;52832.47167528352;54993.72503137459;1407.2400903512194;1439.3738723655244;1441.8269807623153;1449.0291504691882;1451.151076620168;26577.335566610214;27069.323266918484;27170.592441113004;27411;27505.587416189173;37347;37405.251894962494;37415.43876841795;37435.25129497449;37498.43752343662;193815;194200;194735;194767;194814;31051.53422698616;32235.67764322327;32241.355172895943;32380.514292285163;32563.34873302568;41494.96262593459;41516.37725434061;41535.7923210382;41539.37690934699;41667.58332416759;30357.848210758806;30634.387312254075;30782.538261926537;30788.69211307948;30806.845965770925;30610;30643.927462538704;30781.69218307788;30862.382752345275;30905.381892362475;34925;35078.29843403168;35133;35387.82306088453;35419.64580354266;29869.850650746608;30388.24029399283;30646.08061758151;30751.384972300875;31348.52977205298;22849.543009140056;22930.770692293532;23113.72874491919;23153.768462315835;23459.765402345758 17:31:08 INFO - PID 9306 | |2;jquery.html;566.8412844403568;566.9603127781057;572.5076434266527;575.1918554431024;583.1981026089128;21412.785872141274;22041.779582204173;22152.113915443384;22196.223132190356;22207.22274720385;24180;25882.35294117648;25960.091396801115;26002.219933401982;26086.73913260867;6448.516361272911;6601;6609.876321025413;6616.338366163369;6635.037919501669;6212.968935155341;6446.452051575622;6448;6520.054592084169;6569.67151642416;6414.294427612982;6417;6420;6432.099506069154;6439.903401448982;6438.130852334969;6438.7746428875;6441.0982462455295;6443.645599492026;6444.968804991226;5972.820815375546;5981.910271345933;5994.040953447471;6002.489788367994;6008.699565021731;27944.301392465146;28404.573931391045;28952.131436056952;29517.11448656544;29701.405971880657;1974.3879397386856;2007.026592102829;2008.4677560446494;2008.588239410932;2009.336918816787;19266;19813.60372792536;19817.50456238591;19830.206791728313;19855.106520206624;2396.8202384821207;2403.639454081884;2441.9755802441928;2448.6816713827266;2494.164454907608;136949.31525342408;139649.99999999898;140353;140643;140939.2953035228;14485.348159332856;14929.328180231916;14958.102513848986;15010.474633387998;15293;22744.43138921507;22796.772032279467;22820.885895570416;22821.771782281965;22883.88558057199;9706.369086009387;9778;9787.412755234709;9845.261605379625;9845.704628861002;8083.434159608771;8884.8667269991;8913.866292005625;8923.330750193762;9053.68312109073;7835.412344074103;9040.728778136667;9127.726168214964;9156.587953542106;9215.907840921505;2622.960655590168;2677.357434215774;2689.273896048056;2722.7141150178945;2805.719428057219;901.2474583722534;906.71891713569;911.9407238529475;931.4364809290417;962.4754508792585;19160.425187244404;19811.20755169806;20228.595428091357;20239.089240983896;20435.693464598044;976.7899901521207;977.4330888084894;989.0949780950493;993.3592832622918;1035.8601588785568;10880.945595271973;11230.55077796879;11365.488553015135;11394.601188958346;11437.828432573519;473560;481248;485527;486710;486896;111407;112610;113730.43134784274;114489;115313;1130.8473356096822;1150.7008177873813;1198.8561372635165;1203.3501908969233;1209.6975756060986;17738.29046838112;17738.46521635219;17802.643947120727;17852.55368615795;18308.542286442946;55033.724831375584;55270.170947435014;56220.718896405255;57578;57787;71156.64421677859;71289.28710712827;71344;71488;71557;12382.504699811907;12770.42533086032;13517.797233041709;14420;14845.109293442429;22718.65922011138;22967.425814354774;22987;23091.19180828663;23104;18166.636667266845;18379.17293721759;18871.339503117324;19055.61888762189;19074.904625476785;16863.156842157598;17175.57061073483;17381.21784519725;17449.38927137544;17861.28554857843;18971.43085707431;19292.710609341153;19384.418467445998;19496.512587185432;19578;14285.42858285657;14884.7767283493;14896.180710061051;14967.101973881601;14981;18377.448676539727;18667.439976800175;18766;18868.150933207766;18872.433826985212 17:31:08 INFO - PID 9306 | |3;mootools.html;1318.3408295852073;1376.1055314045873;1379.972400551989;1395.5255213227501;1402.1867316956161;14928.701425971487;15312.846871531281;15549.53351399457;15565.29956151973;15580.143092129943;14960.700785984274;15751.369945202177;15755;15786.368545258176;15791.763123553128;2826;2878.2372671242138;2881.625388699466;2885.2786803299177;2888.6966868478717;2701.18964310707;2787.2613757354316;2861.957070643942;2867.1111955293927;2870.856457177143;2853.044230182891;2859.6997315281906;2890.609767681357;2894.1462268630803;2894.5079336512845;2616.2151354593643;2646.2193652872447;2657.853818040007;2660.4413073254736;2779.7081306462733;2597.155924324602;2602.6096085587315;2604.7525485078886;2604.7655710985914;2606.192080455065;14172.57482275523;14509.564713058626;14514.709705805824;14517.564473065824;14534.345954432076;7875.684972601089;8034.276915077671;8291.295239904615;8305.044919834176;8310.418270721051;5500.202470641734;5586.078297080977;5632.887342253132;5728.083506638918;5751.856203594902;1895.3745264062827;1942.873713208637;1944.0171025553998;1975.0914579293542;1981.8810871347766;26242.475150496884;26260.73739262602;26365.34086647849;26393.868030659916;26519.469610607874;6764.594124352555;6788.355106264897;6926.411255043277;6945.47908906834;6966.582005079711;16729.916350418414;16736.99578025298;16743.91628041852;16756.16219189036;16778.41275555374;7494.6252687366505;7948.682052717826;7949.6025198739835;8002.879956800536;8017.198280171936;7432.108147022392;7440.776776696708;7474.551526908294;7552.358049565739;7600.885986710204;4678.929816052762;5155.432902380735;5322.600804939568;5343.545798607084;5345.599080068932;1443.256722787765;1499.7975273338177;1528.8700460460764;1540.1606124662087;1542.282838480112;892.9821403571892;917.0554329041098;917.9036201198778;921.235374639048;926.8795056642593;7509.173990861001;7566.621668916532;7583.27958843909;7584.165741768401;7718.961405192938;973.1582181413169;993.7565296502403;999.0558921818812;1004.7638804880847;1011.4437059617328;4715.127701375257;4781;4787.952120478751;4817.0365926814775;4847.80608775645;20899;21735.782642173377;21787.237446689607;21797;21813.78186218149;21088.261910833353;21882.671759923614;21938.780612193674;22018.889905550368;22104.889475552518;11292.096632269398;11462.254953428;11780.99861511764;11796.115291353011;11809.468573914195;8474.703385381728;8666.263367613823;8726.127387261224;8751.91248087511;8787.384883058125;18773.812261877207;18932.242710291433;19071.71392429087;19307.710384344526;19392.4182274532;18840.905795470935;19531.121099550837;19574.706379404037;19637.803621963598;19672.90163549173;12070;12329.691757706129;12342.81485777696;12348.753024939631;12354.382280886099;14001.929990349983;14019.439222431396;14048.438062477386;14136.363863626368;14153.150810951376;11326.603568875049;11421.37182454973;11544.884551154717;11684.298942063504;11848.28910265387;11018.779624407627;11225.270357426742;11237.3257604544;11301.43492825339;11417.828732568853;9763.414195148314;9885.209183265179;9912.256580756617;9966.352187107816;9977.002299769965;7496.7376141834775;7508.58702771331;7534.924650753642;7547.16981132064;7593.1647518774;6720.563163394364;6750.2237242715755;6792.490563207681;6801.387875091265;6837.042814005944 17:31:08 INFO - PID 9306 | |4;prototype.html;491.77793183937916;503.629832073426;527.3829619345365;528.6378830501108;530.6762874646466;6422.068800024;6449.677516124187;6488.534356944367;6490.156279683646;6540.247871494779;6100.359462256466;6244.625322480654;6324;6364.777232796853;6382.010788327805;5126.026055049548;5135.204043373283;5135.6148288878485;5146.897062058757;5172.172452407625;5037.370328708911;5068.5438310552045;5091.701616087892;5093.719845408519;5181.740912954356;4921.286413470045;5038.773255203506;5043.495650434964;5099.4390617032095;5103.566196873252;4992.950070499285;5016.398032236137;5090.10923088458;5109.744512774347;5121.723360993575;4521.457425109008;4536.183486972343;4548.545145485458;4587.678862479628;4611.1930412177735;6252.406021427957;6267.9686601567155;6281.685915704196;6340.239171299428;6360.395762402564;5763.164341170507;5783.797567085132;5787.247657804501;5836.5914385993;5855.297364316267;6614.371634694697;6618.86762264752;6727.159105111861;6729.495287853381;6808.863822723567;4920.1635721927005;4932.4081110267;4940.456549779521;4965.950340496585;4980.203167493184;7036.859262814766;7045.401140902978;7055.788326350218;7171.426285897116;7174.784756457315;5033.2701758245585;5035.899282014339;5084.6440749147205;5111.131107711698;5126.410462796752;5125.616083657466;5127.53852153308;5160.638755287169;5161.974190129101;5176.145935920531;5775.971120144456;5813.767449302065;5850.619709718854;5854.4730974212525;5871.55963302754;6591.967040164768;6631.171103612049;6744.629045402452;6763.5265531413315;6776.491763117786;1695.6862980348676;1709.6238827458187;1711.3069206971197;1741.4166254304696;1786.5265704588294;1212.078820096733;1219.7316590350108;1236.9876301236873;1238.8761123887718;1241.267652085274;5841.853953651193;5844.912326315109;5858.47273745365;5912.2406462797;5973.729982606582;6659.733610655519;6768.56004359715;6834.316568343225;6840.213375461786;6879.62652884754;5894.440028197314;5927.9703601481715;5932.58471906971;6049.85052839959;6074.355386767904;3652.470391793175;3653.5615726112505;3665.963340366562;3689.6679298863232;3691.3724666806706;6666.400183873112;6679.265280819106;6747.291534388916;6757.256701762803;6758.391744742997;7094.326039026284;7100.715971361191;7102.92897071033;7167.89248161278;7215.567065976058;6613.503987200979;6642.900356494656;6678.766243181465;6694.866102678016;6726.663666816639;6914.688839002358;6954;6959.251095817225;6988.615626140611;7021.6658834823265;6830.556013859084;6852.280510546321;6873.2783057778215;6881.518293719391;6894.620795856276;6488.188976377953;6586.70359833799;6606.6036037837885;6616.503762217757;6652.4345430637;5017.448080711192;5038.362532176984;5065.240213967934;5069.290299358019;5102.08162530748;6853.623050732058;6936;6941.514094013371;6967.512274140761;7005.719771209095;6680;6692.196936367667;6709.161354830646;6772.627505487246;6776.525643204928;6475.676216189266;6637.137172167587;6674.199096108498;6689.431398331197;6697.866042679217;6415.294317625151;6508.414242717989;6522.021696745336;6599.307072757291;6703.4972377070935;6575.24384695747;6762.4928130389435;6783.423409010092;6801.727930882709;6872.347127022925;6474.449671777952;6501.382368674968;6507.446867016357;6535.542512024303;6584.275729669636 17:31:08 INFO - PID 9306 | |5;yui.html;881.7531091294436;904.1907492793946;908.8136931928955;908.8454962656348;920.2637889688248;18174.636507269846;18981.050947452608;19003.71494427583;19020.90489547552;21750.56498870022;20306.18775248992;20636.793632063676;20837.166513339485;20934.790652093514;21015.2644657437;924.7641851327902;937.7046230437425;937.95310234488;938.5494962418044;942.1661829281067;959.4147569982305;999.5651891427252;1000.8999100090006;1001.4892404873531;1008.7629407089328;956.0917128727701;957.090763774417;957.3490026781785;962.8892677342126;1027.1218108517223;961.4952150121172;961.8268711631904;965.932384733069;965.9951700241489;1026.3533973596623;698.1203683358954;704.0460176460873;704.2534912992194;715.2811424518378;741.9109706835214;18419;18502.2599096036;19176.520586985298;19424.320148794723;19439.51401214967;20686.965651717353;20824.27115050966;21210.257640982647;21303.147874085018;21363.145474181016;17212.741808872877;17288.827111728973;17414.129293535272;17415.3033878646;17427.47717568475;3671.6511931366745;3708.295423869456;3709.7959612221316;3710.5176327077306;3710.6474884885893;129158;129933.35033324867;132319.33840330833;133545.33227333898;135556;16080.839191608167;16213.513594592181;16271.83728162727;16476;16505.33978640865;29251.414971700447;30056.699433005826;30063.248418789706;30065.849670751508;30117.247068823453;7814.452988290879;8055.194480551898;8666.78333041679;8993.955030224937;9315.487648179414;4173.290540608104;4206.600372964564;4266.573342665771;4270.615644592001;4276.037891474393;5149.536541711264;5214.504622060898;5237.319148510669;5252.553532949666;5269.314989051475;1081.1566977757361;1092.1644941619668;1102.415719668577;1105.1545567640762;1109.0573012938937;701.7438634898225;708.3553965891015;714.3499415531841;720.8450183210634;728.2353528794745;11290.661280161608;11330.546778128948;11407.543698252142;11745.530178792924;11859.762804743857;686.6909890549273;691.8754624167698;694.3611877073105;697.5291678117251;698.6995591895534;5562.193481945095;5569;5652.604317697722;5679.204911312419;5797.043487824546;107985;109581.45209273903;109630;110022;110349;94015;95672.52163739137;95896;96041;99053;84378.15621843703;84425.57787211148;85705;87328;87585.5620721905;10397.740056498647;10406.843897341394;10590.841137382791;10679.5194216262;10709.08972737326;40559.59440405676;41367.58632413638;41450.585494144674;41626.375604365356;43019.56980430156;71793.64103179451;71806.28193717996;74273;75908.62045689736;76236.61881690557;17714.468565943043;18150.45548633543;18672.25310987545;18806.05969701482;18852.622947541247;16335.918320408322;16868.662626747642;16961.830381696025;19484.31804886879;20583.176672932914;18188.818111818713;18793;18824.905875470537;19190.712139317642;19474.513137171685;17104.743428848327;17150.742738859175;18389.172487238375;18630.161642725838;18721.53196170107;16526.917365413577;16633.916830415772;17868.374606888698;18778.342758003404;18840.811591883907;12001.63995080149;12067.275963442222;12237.938810305892;12563.87436125627;12754.48982040708;13470.595882123553;13707.383167757675;13749.243791591558;14211;14258.42966281337 17:31:08 INFO - PID 9306 | __end_tp_report 17:31:08 INFO - PID 9306 | __start_cc_report 17:31:08 INFO - PID 9306 | _x_x_mozilla_cycle_collect,367 17:31:08 INFO - PID 9306 | __end_cc_report 17:31:08 INFO - PID 9306 | __startTimestamp1504225868057__endTimestamp 17:31:08 INFO - PID 9306 | 17:31:08 INFO - PID 9306 | ------- Summary: start ------- 17:31:08 INFO - PID 9306 | Number of tests: 6 17:31:08 INFO - PID 9306 | 17:31:08 INFO - PID 9306 | [#0] dojo.html Cycles:165 Average:6326.44 Median:6616.07 stddev:747.67 (11.3%) stddev-sans-first:711.85 17:31:08 INFO - PID 9306 | Values: 3322.2 3332.9 3335.0 3354.2 3364.8 6280.4 6425.5 6433.9 6454.1 6511.8 6317.1 6385.7 6393.0 6396.3 6420.4 5876.7 5878.3 5899.2 5916.9 5937.5 5888.5 5899.9 5910.6 5911.6 5917.3 5928.4 5962.4 6026.9 6045.0 6071.3 5846.6 5929.7 6001.3 6021.3 6061.3 5756.4 5762.4 5863.1 5889.2 5909.1 6150.0 6251.5 6301.3 6328.3 6342.3 6717.5 6743.6 6832.5 6852.2 6862.2 6782.2 6825.0 6906.6 6916.0 6928.6 6378.6 6407.8 6416.5 6428.8 6490.5 7114.1 7117.5 7122.5 7123.9 7153.5 6721.2 6740.3 6808.9 6823.3 6826.7 6847.2 6866.3 6866.8 6948.4 6955.1 6633.7 6785.3 6802.0 6807.0 6812.3 6599.7 6617.1 6622.7 6624.7 6650.6 5221.3 5221.8 5224.8 5225.2 5231.3 4636.4 4637.1 4638.0 4722.3 4830.4 6008.6 6059.7 6080.1 6084.7 6088.5 6815.6 6903.3 6904.1 6910.4 6914.4 5924.5 5931.6 6042.5 6043.5 6114.0 5946.1 5950.9 6004.7 6024.4 6025.4 6488.1 6550.7 6654.0 6716.6 6716.6 6971.4 6990.0 7000.8 7029.5 7032.4 6696.0 6708.2 6745.9 6781.7 6797.3 6813.1 6819.9 6844.8 6937.7 6943.3 6669.8 6778.9 6891.1 6968.9 7087.2 6619.5 6691.9 6711.9 6713.8 6770.7 6707.4 6786.7 6801.8 6806.0 6807.8 6619.5 6632.1 6643.6 6731.9 6742.3 6588.5 6735.0 6739.1 6739.9 6832.9 6471.2 6488.9 6547.1 6616.1 6627.5 17:31:08 INFO - PID 9306 | 17:31:08 INFO - PID 9306 | [#1] ext.html Cycles:180 Average:36778.81 Median:3453.18 stddev:53069.07 (1536.8%) stddev-sans-first:53149.18 17:31:08 INFO - PID 9306 | Values: 798.6 822.4 850.6 882.1 883.5 39834.0 40488.2 40558.2 40577.8 40683.0 36189.3 36354.5 36395.8 36396.8 36441.7 6760.5 6848.8 6855.5 6858.2 6870.0 6804.4 6865.7 6874.7 6879.3 6903.9 6851.1 7469.2 7601.8 7613.5 7616.6 7522.8 7564.1 7565.9 7565.9 7569.4 7382.2 7393.5 7474.6 7493.7 7494.2 36246.0 36494.0 36542.5 36588.3 36619.8 3439.1 3450.7 3455.6 3456.4 3461.2 30044.7 30162.8 30178.0 30216.5 30265.5 5134.9 5144.1 5148.5 5173.4 5173.6 185649.0 185759.0 186894.0 187018.0 187460.0 47759.5 47789.0 48029.3 48051.8 48054.8 49899.0 49919.3 49934.5 49976.3 50010.5 14442.6 14596.1 14609.3 14610.7 14658.2 11938.2 11938.2 11963.9 11966.7 11978.1 14115.8 14187.1 14192.1 14201.7 14335.6 5710.1 5747.1 5747.4 5760.8 5790.4 988.9 1011.3 1019.6 1026.9 1030.5 22564.7 22571.3 22607.7 22708.7 22790.2 1090.2 1108.4 1111.3 1116.1 1131.3 12121.3 12261.6 12279.4 12298.9 12303.4 229185.0 230575.0 230960.0 230983.0 230989.0 50309.7 50479.7 51580.2 52832.5 54993.7 1407.2 1439.4 1441.8 1449.0 1451.2 26577.3 27069.3 27170.6 27411.0 27505.6 37347.0 37405.3 37415.4 37435.3 37498.4 193815.0 194200.0 194735.0 194767.0 194814.0 31051.5 32235.7 32241.4 32380.5 32563.3 41495.0 41516.4 41535.8 41539.4 41667.6 30357.8 30634.4 30782.5 30788.7 30806.8 30610.0 30643.9 30781.7 30862.4 30905.4 34925.0 35078.3 35133.0 35387.8 35419.6 29869.9 30388.2 30646.1 30751.4 31348.5 22849.5 22930.8 23113.7 23153.8 23459.8 17:31:08 INFO - PID 9306 | 17:31:08 INFO - PID 9306 | [#2] jquery.html Cycles:180 Average:34513.14 Median:12750.41 stddev:81475.18 (639.0%) stddev-sans-first:81663.87 17:31:08 INFO - PID 9306 | Values: 566.8 567.0 572.5 575.2 583.2 21412.8 22041.8 22152.1 22196.2 22207.2 24180.0 25882.4 25960.1 26002.2 26086.7 6448.5 6601.0 6609.9 6616.3 6635.0 6213.0 6446.5 6448.0 6520.1 6569.7 6414.3 6417.0 6420.0 6432.1 6439.9 6438.1 6438.8 6441.1 6443.6 6445.0 5972.8 5981.9 5994.0 6002.5 6008.7 27944.3 28404.6 28952.1 29517.1 29701.4 1974.4 2007.0 2008.5 2008.6 2009.3 19266.0 19813.6 19817.5 19830.2 19855.1 2396.8 2403.6 2442.0 2448.7 2494.2 136949.3 139650.0 140353.0 140643.0 140939.3 14485.3 14929.3 14958.1 15010.5 15293.0 22744.4 22796.8 22820.9 22821.8 22883.9 9706.4 9778.0 9787.4 9845.3 9845.7 8083.4 8884.9 8913.9 8923.3 9053.7 7835.4 9040.7 9127.7 9156.6 9215.9 2623.0 2677.4 2689.3 2722.7 2805.7 901.2 906.7 911.9 931.4 962.5 19160.4 19811.2 20228.6 20239.1 20435.7 976.8 977.4 989.1 993.4 1035.9 10880.9 11230.6 11365.5 11394.6 11437.8 473560.0 481248.0 485527.0 486710.0 486896.0 111407.0 112610.0 113730.4 114489.0 115313.0 1130.8 1150.7 1198.9 1203.4 1209.7 17738.3 17738.5 17802.6 17852.6 18308.5 55033.7 55270.2 56220.7 57578.0 57787.0 71156.6 71289.3 71344.0 71488.0 71557.0 12382.5 12770.4 13517.8 14420.0 14845.1 22718.7 22967.4 22987.0 23091.2 23104.0 18166.6 18379.2 18871.3 19055.6 19074.9 16863.2 17175.6 17381.2 17449.4 17861.3 18971.4 19292.7 19384.4 19496.5 19578.0 14285.4 14884.8 14896.2 14967.1 14981.0 18377.4 18667.4 18766.0 18868.2 18872.4 17:31:08 INFO - PID 9306 | 17:31:08 INFO - PID 9306 | [#3] mootools.html Cycles:180 Average:9400.24 Median:26379.60 stddev:6705.52 (25.4%) stddev-sans-first:6696.83 17:31:08 INFO - PID 9306 | Values: 1318.3 1376.1 1380.0 1395.5 1402.2 14928.7 15312.8 15549.5 15565.3 15580.1 14960.7 15751.4 15755.0 15786.4 15791.8 2826.0 2878.2 2881.6 2885.3 2888.7 2701.2 2787.3 2862.0 2867.1 2870.9 2853.0 2859.7 2890.6 2894.1 2894.5 2616.2 2646.2 2657.9 2660.4 2779.7 2597.2 2602.6 2604.8 2604.8 2606.2 14172.6 14509.6 14514.7 14517.6 14534.3 7875.7 8034.3 8291.3 8305.0 8310.4 5500.2 5586.1 5632.9 5728.1 5751.9 1895.4 1942.9 1944.0 1975.1 1981.9 26242.5 26260.7 26365.3 26393.9 26519.5 6764.6 6788.4 6926.4 6945.5 6966.6 16729.9 16737.0 16743.9 16756.2 16778.4 7494.6 7948.7 7949.6 8002.9 8017.2 7432.1 7440.8 7474.6 7552.4 7600.9 4678.9 5155.4 5322.6 5343.5 5345.6 1443.3 1499.8 1528.9 1540.2 1542.3 893.0 917.1 917.9 921.2 926.9 7509.2 7566.6 7583.3 7584.2 7719.0 973.2 993.8 999.1 1004.8 1011.4 4715.1 4781.0 4788.0 4817.0 4847.8 20899.0 21735.8 21787.2 21797.0 21813.8 21088.3 21882.7 21938.8 22018.9 22104.9 11292.1 11462.3 11781.0 11796.1 11809.5 8474.7 8666.3 8726.1 8751.9 8787.4 18773.8 18932.2 19071.7 19307.7 19392.4 18840.9 19531.1 19574.7 19637.8 19672.9 12070.0 12329.7 12342.8 12348.8 12354.4 14001.9 14019.4 14048.4 14136.4 14153.2 11326.6 11421.4 11544.9 11684.3 11848.3 11018.8 11225.3 11237.3 11301.4 11417.8 9763.4 9885.2 9912.3 9966.4 9977.0 7496.7 7508.6 7534.9 7547.2 7593.2 6720.6 6750.2 6792.5 6801.4 6837.0 17:31:08 INFO - PID 9306 | 17:31:08 INFO - PID 9306 | [#4] prototype.html Cycles:180 Average:5645.24 Median:6332.12 stddev:1599.63 (25.3%) stddev-sans-first:1556.65 17:31:08 INFO - PID 9306 | Values: 491.8 503.6 527.4 528.6 530.7 6422.1 6449.7 6488.5 6490.2 6540.2 6100.4 6244.6 6324.0 6364.8 6382.0 5126.0 5135.2 5135.6 5146.9 5172.2 5037.4 5068.5 5091.7 5093.7 5181.7 4921.3 5038.8 5043.5 5099.4 5103.6 4993.0 5016.4 5090.1 5109.7 5121.7 4521.5 4536.2 4548.5 4587.7 4611.2 6252.4 6268.0 6281.7 6340.2 6360.4 5763.2 5783.8 5787.2 5836.6 5855.3 6614.4 6618.9 6727.2 6729.5 6808.9 4920.2 4932.4 4940.5 4966.0 4980.2 7036.9 7045.4 7055.8 7171.4 7174.8 5033.3 5035.9 5084.6 5111.1 5126.4 5125.6 5127.5 5160.6 5162.0 5176.1 5776.0 5813.8 5850.6 5854.5 5871.6 6592.0 6631.2 6744.6 6763.5 6776.5 1695.7 1709.6 1711.3 1741.4 1786.5 1212.1 1219.7 1237.0 1238.9 1241.3 5841.9 5844.9 5858.5 5912.2 5973.7 6659.7 6768.6 6834.3 6840.2 6879.6 5894.4 5928.0 5932.6 6049.9 6074.4 3652.5 3653.6 3666.0 3689.7 3691.4 6666.4 6679.3 6747.3 6757.3 6758.4 7094.3 7100.7 7102.9 7167.9 7215.6 6613.5 6642.9 6678.8 6694.9 6726.7 6914.7 6954.0 6959.3 6988.6 7021.7 6830.6 6852.3 6873.3 6881.5 6894.6 6488.2 6586.7 6606.6 6616.5 6652.4 5017.4 5038.4 5065.2 5069.3 5102.1 6853.6 6936.0 6941.5 6967.5 7005.7 6680.0 6692.2 6709.2 6772.6 6776.5 6475.7 6637.1 6674.2 6689.4 6697.9 6415.3 6508.4 6522.0 6599.3 6703.5 6575.2 6762.5 6783.4 6801.7 6872.3 6474.4 6501.4 6507.4 6535.5 6584.3 17:31:08 INFO - PID 9306 | 17:31:08 INFO - PID 9306 | [#5] yui.html Cycles:180 Average:23827.92 Median:21112.76 stddev:32728.31 (155.0%) stddev-sans-first:32774.77 17:31:08 INFO - PID 9306 | Values: 881.8 904.2 908.8 908.8 920.3 18174.6 18981.1 19003.7 19020.9 21750.6 20306.2 20636.8 20837.2 20934.8 21015.3 924.8 937.7 938.0 938.5 942.2 959.4 999.6 1000.9 1001.5 1008.8 956.1 957.1 957.3 962.9 1027.1 961.5 961.8 965.9 966.0 1026.4 698.1 704.0 704.3 715.3 741.9 18419.0 18502.3 19176.5 19424.3 19439.5 20687.0 20824.3 21210.3 21303.1 21363.1 17212.7 17288.8 17414.1 17415.3 17427.5 3671.7 3708.3 3709.8 3710.5 3710.6 129158.0 129933.4 132319.3 133545.3 135556.0 16080.8 16213.5 16271.8 16476.0 16505.3 29251.4 30056.7 30063.2 30065.8 30117.2 7814.5 8055.2 8666.8 8994.0 9315.5 4173.3 4206.6 4266.6 4270.6 4276.0 5149.5 5214.5 5237.3 5252.6 5269.3 1081.2 1092.2 1102.4 1105.2 1109.1 701.7 708.4 714.3 720.8 728.2 11290.7 11330.5 11407.5 11745.5 11859.8 686.7 691.9 694.4 697.5 698.7 5562.2 5569.0 5652.6 5679.2 5797.0 107985.0 109581.5 109630.0 110022.0 110349.0 94015.0 95672.5 95896.0 96041.0 99053.0 84378.2 84425.6 85705.0 87328.0 87585.6 10397.7 10406.8 10590.8 10679.5 10709.1 40559.6 41367.6 41450.6 41626.4 43019.6 71793.6 71806.3 74273.0 75908.6 76236.6 17714.5 18150.5 18672.3 18806.1 18852.6 16335.9 16868.7 16961.8 19484.3 20583.2 18188.8 18793.0 18824.9 19190.7 19474.5 17104.7 17150.7 18389.2 18630.2 18721.5 16526.9 16633.9 17868.4 18778.3 18840.8 12001.6 12067.3 12237.9 12563.9 12754.5 13470.6 13707.4 13749.2 14211.0 14258.4 17:31:08 INFO - PID 9306 | -------- Summary: end -------- 17:31:08 INFO - PID 9306 | 17:31:08 INFO - TEST-INFO | 9306: exit 0 17:31:08 INFO - TEST-OK | dromaeo_css | took 1111499ms 17:31:08 INFO - TEST-START | kraken 17:31:08 INFO - Initialising browser for kraken test... 17:31:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50831/getInfo.html -profile /tmp/tmpEFfxWa/profile 17:31:08 INFO - TEST-INFO | started process 9691 (/builds/slave/test/build/application/firefox/firefox http://localhost:50831/getInfo.html) 17:31:13 INFO - TEST-INFO | 9691: exit 0 17:31:13 INFO - Browser initialized. 17:31:13 INFO - Running cycle 1/1 for kraken test... 17:31:13 INFO - Using env: {'DISPLAY': ':0', 17:31:13 INFO - 'HOME': '/home/cltbld', 17:31:13 INFO - 'JSGC_DISABLE_POISONING': '1', 17:31:13 INFO - 'LANG': 'en_US.UTF-8', 17:31:13 INFO - 'LANGUAGE': 'en_US:en', 17:31:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:31:13 INFO - 'LOGNAME': 'cltbld', 17:31:13 INFO - 'MAIL': '/var/mail/cltbld', 17:31:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:31:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:31:13 INFO - 'MOZ_CRASHREPORTER': '1', 17:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:31:13 INFO - 'MOZ_NO_REMOTE': '1', 17:31:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:13 INFO - 'NO_EM_RESTART': '1', 17:31:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:13 INFO - 'PWD': '/builds/slave/test', 17:31:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:31:13 INFO - 'RUST_BACKTRACE': 'full', 17:31:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:31:13 INFO - 'SHELL': '/bin/bash', 17:31:13 INFO - 'SHLVL': '1', 17:31:13 INFO - 'STYLO_FORCE_ENABLED': '1', 17:31:13 INFO - 'TERM': 'linux', 17:31:13 INFO - 'TMOUT': '86400', 17:31:13 INFO - 'USER': 'cltbld', 17:31:13 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695', 17:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:13 INFO - '_': '/tools/buildbot/bin/python'} 17:31:13 INFO - TEST-INFO | started process 9887 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEFfxWa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:31:14 INFO - PID 9887 | 17:31:14 INFO - PID 9887 | (/builds/slave/test/build/application/firefox/firefox:9938): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:31:14 INFO - PID 9887 | 17:31:25 INFO - PID 9887 | RSS: Main: 173432832 17:31:25 INFO - PID 9887 | 17:31:27 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50831/tests/kraken/driver.html?testName=audio-beat-detection) 17:31:28 INFO - PID 9887 | RSS: Main: 177930240 17:31:28 INFO - PID 9887 | 17:31:31 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50831/tests/kraken/driver.html?testName=audio-dft) 17:31:32 INFO - PID 9887 | RSS: Main: 178638848 17:31:32 INFO - PID 9887 | 17:31:35 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50831/tests/kraken/driver.html?testName=audio-fft) 17:31:35 INFO - PID 9887 | RSS: Main: 173162496 17:31:35 INFO - PID 9887 | 17:31:37 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50831/tests/kraken/driver.html?testName=audio-oscillator) 17:31:38 INFO - PID 9887 | RSS: Main: 173178880 17:31:38 INFO - PID 9887 | 17:31:40 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50831/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:31:41 INFO - PID 9887 | RSS: Main: 173379584 17:31:41 INFO - PID 9887 | 17:31:44 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50831/tests/kraken/driver.html?testName=imaging-darkroom) 17:31:44 INFO - PID 9887 | RSS: Main: 176070656 17:31:44 INFO - PID 9887 | 17:31:48 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50831/tests/kraken/driver.html?testName=imaging-desaturate) 17:31:49 INFO - PID 9887 | RSS: Main: 176480256 17:31:49 INFO - PID 9887 | 17:31:51 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50831/tests/kraken/driver.html?testName=json-parse-financial) 17:31:52 INFO - PID 9887 | RSS: Main: 176431104 17:31:52 INFO - PID 9887 | 17:31:54 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50831/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:31:54 INFO - PID 9887 | RSS: Main: 176312320 17:31:54 INFO - PID 9887 | 17:31:56 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50831/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:31:57 INFO - PID 9887 | RSS: Main: 176377856 17:31:57 INFO - PID 9887 | 17:31:59 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50831/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:31:59 INFO - PID 9887 | RSS: Main: 173117440 17:31:59 INFO - PID 9887 | 17:32:02 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50831/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:32:03 INFO - PID 9887 | RSS: Main: 174010368 17:32:03 INFO - PID 9887 | 17:32:06 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50831/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:32:06 INFO - PID 9887 | RSS: Main: 173891584 17:32:06 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | Cycle 1(1): loaded http://localhost:50831/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50831/tests/kraken/driver.html?testName=ai-astar) 17:32:08 INFO - PID 9887 | RSS: Main: 175673344 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | __start_tp_report 17:32:08 INFO - PID 9887 | _x_x_mozilla_page_load 17:32:08 INFO - PID 9887 | _x_x_mozilla_page_load_details 17:32:08 INFO - PID 9887 | |i|pagename|runs| 17:32:08 INFO - PID 9887 | |0;ai-astar;98;107;100;108;106;109;107;99;110;108 17:32:08 INFO - PID 9887 | |1;audio-beat-detection;148;114;140;182;116;146;143;117;178;124 17:32:08 INFO - PID 9887 | |2;audio-dft;162;161;163;161;162;162;163;166;161;162 17:32:08 INFO - PID 9887 | |3;audio-fft;73;74;73;181;74;74;73;73;147;74 17:32:08 INFO - PID 9887 | |4;audio-oscillator;80;78;131;79;79;80;142;78;79;131 17:32:08 INFO - PID 9887 | |5;imaging-gaussian-blur;114;114;114;114;113;114;113;113;113;114 17:32:08 INFO - PID 9887 | |6;imaging-darkroom;147;148;148;147;147;147;148;147;147;147 17:32:08 INFO - PID 9887 | |7;imaging-desaturate;94;94;94;94;94;94;95;93;94;93 17:32:08 INFO - PID 9887 | |8;json-parse-financial;53;54;61;53;52;52;53;54;52;52 17:32:08 INFO - PID 9887 | |9;json-stringify-tinderbox;42;39;38;38;38;39;38;42;38;38 17:32:08 INFO - PID 9887 | |10;stanford-crypto-aes;75;76;74;74;73;75;75;73;74;74 17:32:08 INFO - PID 9887 | |11;stanford-crypto-ccm;119;138;121;125;116;129;125;135;124;161 17:32:08 INFO - PID 9887 | |12;stanford-crypto-pbkdf2;170;170;169;167;171;172;171;170;171;170 17:32:08 INFO - PID 9887 | |13;stanford-crypto-sha256-iterative;65;64;64;63;64;63;63;63;64;61 17:32:08 INFO - PID 9887 | __end_tp_report 17:32:08 INFO - PID 9887 | __start_cc_report 17:32:08 INFO - PID 9887 | _x_x_mozilla_cycle_collect,933 17:32:08 INFO - PID 9887 | __end_cc_report 17:32:08 INFO - PID 9887 | __startTimestamp1504225928786__endTimestamp 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | ------- Summary: start ------- 17:32:08 INFO - PID 9887 | Number of tests: 14 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#0] ai-astar Cycles:10 Average:105.20 Median:108.50 stddev:4.44 (4.1%) stddev-sans-first:3.87 17:32:08 INFO - PID 9887 | Values: 98.0 107.0 100.0 108.0 106.0 109.0 107.0 99.0 110.0 108.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:144.50 stddev:24.38 (16.9%) stddev-sans-first:25.71 17:32:08 INFO - PID 9887 | Values: 148.0 114.0 140.0 182.0 116.0 146.0 143.0 117.0 178.0 124.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.00 stddev:1.49 (0.9%) stddev-sans-first:1.58 17:32:08 INFO - PID 9887 | Values: 162.0 161.0 163.0 161.0 162.0 162.0 163.0 166.0 161.0 162.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#3] audio-fft Cycles:10 Average:91.60 Median:73.50 stddev:38.99 (53.1%) stddev-sans-first:40.77 17:32:08 INFO - PID 9887 | Values: 73.0 74.0 73.0 181.0 74.0 74.0 73.0 73.0 147.0 74.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:79.00 stddev:27.06 (34.3%) stddev-sans-first:28.10 17:32:08 INFO - PID 9887 | Values: 80.0 78.0 131.0 79.0 79.0 80.0 142.0 78.0 79.0 131.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 17:32:08 INFO - PID 9887 | Values: 114.0 114.0 114.0 114.0 113.0 114.0 113.0 113.0 113.0 114.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 17:32:08 INFO - PID 9887 | Values: 147.0 148.0 148.0 147.0 147.0 147.0 148.0 147.0 147.0 147.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 17:32:08 INFO - PID 9887 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 93.0 94.0 93.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.00 stddev:2.72 (5.1%) stddev-sans-first:2.87 17:32:08 INFO - PID 9887 | Values: 53.0 54.0 61.0 53.0 52.0 52.0 53.0 54.0 52.0 52.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#9] json-stringify-tinderbox Cycles:10 Average:39.00 Median:38.50 stddev:1.63 (4.2%) stddev-sans-first:1.32 17:32:08 INFO - PID 9887 | Values: 42.0 39.0 38.0 38.0 38.0 39.0 38.0 42.0 38.0 38.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:74.50 stddev:0.95 (1.3%) stddev-sans-first:0.97 17:32:08 INFO - PID 9887 | Values: 75.0 76.0 74.0 74.0 73.0 75.0 75.0 73.0 74.0 74.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#11] stanford-crypto-ccm Cycles:10 Average:129.30 Median:127.00 stddev:13.04 (10.3%) stddev-sans-first:13.29 17:32:08 INFO - PID 9887 | Values: 119.0 138.0 121.0 125.0 116.0 129.0 125.0 135.0 124.0 161.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.10 Median:170.50 stddev:1.37 (0.8%) stddev-sans-first:1.45 17:32:08 INFO - PID 9887 | Values: 170.0 170.0 169.0 167.0 171.0 172.0 171.0 170.0 171.0 170.0 17:32:08 INFO - PID 9887 | 17:32:08 INFO - PID 9887 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:64.00 stddev:1.07 (1.7%) stddev-sans-first:0.97 17:32:08 INFO - PID 9887 | Values: 65.0 64.0 64.0 63.0 64.0 63.0 63.0 63.0 64.0 61.0 17:32:08 INFO - PID 9887 | -------- Summary: end -------- 17:32:08 INFO - PID 9887 | 17:32:09 INFO - PID 9887 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:32:09 INFO - TEST-INFO | 9887: exit 0 17:32:09 INFO - TEST-OK | kraken | took 60860ms 17:32:09 INFO - SUITE-END | took 1172s 17:32:09 INFO - Completed test suite (00:19:32) 17:32:09 INFO - javascript benchmark 17:32:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8550.55796185285, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3322.235885746278, 3332.90001299961, 3334.983325083375, 3354.2452948086698, 3364.7981121132734, 6280.434760871521, 6425.48596112311, 6433.935660643398, 6454.0641606966965, 6511.772087976922, 6317.052442133682, 6385.712642931067, 6392.968035159829, 6396.328385519511, 6420.390062944013, 5876.706164691748, 5878.323992740826, 5899.174115623816, 5916.88166236675, 5937.4656280934705, 5888.470037696607, 5899.852503687399, 5910.645361278315, 5911.556633252501, 5917.289925208981, 5928.40715928408, 5962.403759624046, 6026.9095963560585, 6045.03279475284, 6071.301800292957, 5846.590738648296, 5929.73316200772, 6001.339852616209, 6021.307549631804, 6061.272647282311, 5756.395578464243, 5762.366139724627, 5863.061910094364, 5889.204957330749, 5909.113632955048, 6150.0, 6251.499880009588, 6301.275353334334, 6328.303886572473, 6342.334054924213, 6717.496187785936, 6743.629100399476, 6832.521723479358, 6852.246252912176, 6862.176538815374, 6782.152230971129, 6825.044493770876, 6906.585604863724, 6916.031755554226, 6928.584284942919, 6378.649174295412, 6407.8398040048905, 6416.518761092937, 6428.8071357859335, 6490.513211509156, 7114.110736157981, 7117.501774875813, 7122.465815063891, 7123.857522849514, 7153.535020223669, 6721.1598550181225, 6740.29226931175, 6808.863822723518, 6823.283555226729, 6826.658667066627, 6847.1783385994, 6866.313368663094, 6866.828329291808, 6948.409385202213, 6955.130608673916, 6633.668316584151, 6785.287544807823, 6802.0, 6807.047013418125, 6812.250652428229, 6599.73601055962, 6617.073609694647, 6622.701978410983, 6624.668766561749, 6650.567713098633, 5221.32122824038, 5221.843344699665, 5224.843254702365, 5225.216217567396, 5231.293775340357, 4636.4436267647425, 4637.072585482917, 4638.0260145369175, 4722.268048452495, 4830.396200474941, 6008.639481631116, 6059.697015149225, 6080.0879868019265, 6084.726187321581, 6088.452039316394, 6815.625140617214, 6903.275156108637, 6904.1024666794, 6910.44716422685, 6914.412274956685, 5924.496417804448, 5931.555133364928, 6042.486388657014, 6043.456088951927, 6113.96943015291, 5946.137810017523, 5950.9107363389585, 6004.729787159588, 6024.367441418675, 6025.367336429612, 6488.059231411513, 6550.672466376662, 6653.9667301663185, 6716.597004179667, 6716.597004179667, 6971.372576468142, 6989.965050174716, 7000.7549735759, 7029.507934444744, 7032.367086962198, 6695.966520167368, 6708.161479815023, 6745.898811517733, 6781.728730850711, 6797.25230224665, 6813.080234168324, 6819.863602728017, 6844.760433385007, 6937.653117344012, 6943.340382663639, 6669.83325416849, 6778.8983165251575, 6891.104156459628, 6968.860622787617, 7087.22040575526, 6619.536632435684, 6691.866162676816, 6711.865762684816, 6713.832154196184, 6770.6614669265355, 6707.429868461236, 6786.69459874297, 6801.795946121624, 6806.047153398627, 6807.829804254933, 6619.50353723463, 6632.071509988702, 6643.60138391698, 6731.932680673326, 6742.25835158142, 6588.472922166311, 6734.966325168343, 6739.123913891162, 6739.898901516383, 6832.863342733018, 6471.223453185459, 6488.870222595616, 6547.083408322934, 6616.073749674953, 6627.502937279627], "value": 6271.154844187831, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.6366203377464, 822.3503432288493, 850.6299759604573, 882.1178821178823, 883.4831623500248, 39834.0, 40488.19023619526, 40558.18883622326, 40577.79711101444, 40683.0, 36189.27621447576, 36354.454683179705, 36395.81802090986, 36396.81801590995, 36441.724539641116, 6760.526763126571, 6848.794536163923, 6855.520113592051, 6858.177018757731, 6870.038194652728, 6804.421624161952, 6865.691043903012, 6874.656267186646, 6879.27767584404, 6903.930960690379, 6851.109355783766, 7469.21573234808, 7601.847963040764, 7613.467057305991, 7616.581088040156, 7522.774316770506, 7564.092308922937, 7565.886511702329, 7565.886511702329, 7569.356604688608, 7382.151052628963, 7393.482456228066, 7474.551526908403, 7493.700251989914, 7494.213107623676, 36246.0, 36494.0, 36542.45186322207, 36588.26823463516, 36619.81690091533, 3439.105832483547, 3450.723942084627, 3455.6371580984137, 3456.4296891012955, 3461.152017755641, 30044.699553004408, 30162.84918575415, 30178.0, 30216.54675179874, 30265.546016809767, 5134.922976155361, 5144.0740666679985, 5148.459411761749, 5173.430922598549, 5173.611979101583, 185649.0, 185759.0, 186893.99999999863, 187018.0, 187460.0, 47759.52240477551, 47789.0, 48029.27956080592, 48051.75974120107, 48054.79863003383, 49899.0, 49919.25121123186, 49934.504584678725, 49976.25035624469, 50010.49989500131, 14442.638934026734, 14596.051256668283, 14609.269536523343, 14610.707785844224, 14658.19379934114, 11938.164328496921, 11938.164328497096, 11963.940180299043, 11966.743491933395, 11978.101642376856, 14115.78826317606, 14187.14877107377, 14192.148471091768, 14201.715965680629, 14335.641608959859, 5710.114932185435, 5747.080467125324, 5747.3677895431465, 5760.798372056958, 5790.36306006339, 988.8714467119372, 1011.3476807459148, 1019.5870672377699, 1026.861373714539, 1030.5156576409076, 22564.66153007706, 22571.322860314216, 22607.660885086738, 22708.65937010946, 22790.202342918248, 1090.1551297744154, 1108.3571528513444, 1111.349860331699, 1116.0680831505783, 1131.2816361610437, 12121.333326667118, 12261.570845020382, 12279.447424865903, 12298.877011229773, 12303.384830758427, 229185.0, 230575.0, 230960.0, 230983.0, 230989.0, 50309.748451258245, 50479.747601261755, 51580.22629660558, 52832.47167528352, 54993.72503137459, 1407.2400903512194, 1439.3738723655244, 1441.8269807623153, 1449.0291504691882, 1451.151076620168, 26577.335566610214, 27069.323266918484, 27170.592441113004, 27411.0, 27505.587416189173, 37347.0, 37405.251894962494, 37415.43876841795, 37435.25129497449, 37498.43752343662, 193815.0, 194200.0, 194735.0, 194767.0, 194814.0, 31051.53422698616, 32235.67764322327, 32241.355172895943, 32380.514292285163, 32563.34873302568, 41494.96262593459, 41516.37725434061, 41535.7923210382, 41539.37690934699, 41667.58332416759, 30357.848210758806, 30634.387312254075, 30782.538261926537, 30788.69211307948, 30806.845965770925, 30610.0, 30643.927462538704, 30781.69218307788, 30862.382752345275, 30905.381892362475, 34925.0, 35078.29843403168, 35133.0, 35387.82306088453, 35419.64580354266, 29869.850650746608, 30388.24029399283, 30646.08061758151, 30751.384972300875, 31348.52977205298, 22849.543009140056, 22930.770692293532, 23113.72874491919, 23153.768462315835, 23459.765402345758], "value": 16691.511439386653, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.8412844403568, 566.9603127781057, 572.5076434266527, 575.1918554431024, 583.1981026089128, 21412.785872141274, 22041.779582204173, 22152.113915443384, 22196.223132190356, 22207.22274720385, 24180.0, 25882.35294117648, 25960.091396801115, 26002.219933401982, 26086.73913260867, 6448.516361272911, 6601.0, 6609.876321025413, 6616.338366163369, 6635.037919501669, 6212.968935155341, 6446.452051575622, 6448.0, 6520.054592084169, 6569.67151642416, 6414.294427612982, 6417.0, 6420.0, 6432.099506069154, 6439.903401448982, 6438.130852334969, 6438.7746428875, 6441.0982462455295, 6443.645599492026, 6444.968804991226, 5972.820815375546, 5981.910271345933, 5994.040953447471, 6002.489788367994, 6008.699565021731, 27944.301392465146, 28404.573931391045, 28952.131436056952, 29517.11448656544, 29701.405971880657, 1974.3879397386856, 2007.026592102829, 2008.4677560446494, 2008.588239410932, 2009.336918816787, 19266.0, 19813.60372792536, 19817.50456238591, 19830.206791728313, 19855.106520206624, 2396.8202384821207, 2403.639454081884, 2441.9755802441928, 2448.6816713827266, 2494.164454907608, 136949.31525342408, 139649.99999999898, 140353.0, 140643.0, 140939.2953035228, 14485.348159332856, 14929.328180231916, 14958.102513848986, 15010.474633387998, 15293.0, 22744.43138921507, 22796.772032279467, 22820.885895570416, 22821.771782281965, 22883.88558057199, 9706.369086009387, 9778.0, 9787.412755234709, 9845.261605379625, 9845.704628861002, 8083.434159608771, 8884.8667269991, 8913.866292005625, 8923.330750193762, 9053.68312109073, 7835.412344074103, 9040.728778136667, 9127.726168214964, 9156.587953542106, 9215.907840921505, 2622.960655590168, 2677.357434215774, 2689.273896048056, 2722.7141150178945, 2805.719428057219, 901.2474583722534, 906.71891713569, 911.9407238529475, 931.4364809290417, 962.4754508792585, 19160.425187244404, 19811.20755169806, 20228.595428091357, 20239.089240983896, 20435.693464598044, 976.7899901521207, 977.4330888084894, 989.0949780950493, 993.3592832622918, 1035.8601588785568, 10880.945595271973, 11230.55077796879, 11365.488553015135, 11394.601188958346, 11437.828432573519, 473560.0, 481248.0, 485527.0, 486710.0, 486896.0, 111407.0, 112610.0, 113730.43134784274, 114489.0, 115313.0, 1130.8473356096822, 1150.7008177873813, 1198.8561372635165, 1203.3501908969233, 1209.6975756060986, 17738.29046838112, 17738.46521635219, 17802.643947120727, 17852.55368615795, 18308.542286442946, 55033.724831375584, 55270.170947435014, 56220.718896405255, 57578.0, 57787.0, 71156.64421677859, 71289.28710712827, 71344.0, 71488.0, 71557.0, 12382.504699811907, 12770.42533086032, 13517.797233041709, 14420.0, 14845.109293442429, 22718.65922011138, 22967.425814354774, 22987.0, 23091.19180828663, 23104.0, 18166.636667266845, 18379.17293721759, 18871.339503117324, 19055.61888762189, 19074.904625476785, 16863.156842157598, 17175.57061073483, 17381.21784519725, 17449.38927137544, 17861.28554857843, 18971.43085707431, 19292.710609341153, 19384.418467445998, 19496.512587185432, 19578.0, 14285.42858285657, 14884.7767283493, 14896.180710061051, 14967.101973881601, 14981.0, 18377.448676539727, 18667.439976800175, 18766.0, 18868.150933207766, 18872.433826985212], "value": 11873.99185160739, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.3408295852073, 1376.1055314045873, 1379.972400551989, 1395.5255213227501, 1402.1867316956161, 14928.701425971487, 15312.846871531281, 15549.53351399457, 15565.29956151973, 15580.143092129943, 14960.700785984274, 15751.369945202177, 15755.0, 15786.368545258176, 15791.763123553128, 2826.0, 2878.2372671242138, 2881.625388699466, 2885.2786803299177, 2888.6966868478717, 2701.18964310707, 2787.2613757354316, 2861.957070643942, 2867.1111955293927, 2870.856457177143, 2853.044230182891, 2859.6997315281906, 2890.609767681357, 2894.1462268630803, 2894.5079336512845, 2616.2151354593643, 2646.2193652872447, 2657.853818040007, 2660.4413073254736, 2779.7081306462733, 2597.155924324602, 2602.6096085587315, 2604.7525485078886, 2604.7655710985914, 2606.192080455065, 14172.57482275523, 14509.564713058626, 14514.709705805824, 14517.564473065824, 14534.345954432076, 7875.684972601089, 8034.276915077671, 8291.295239904615, 8305.044919834176, 8310.418270721051, 5500.202470641734, 5586.078297080977, 5632.887342253132, 5728.083506638918, 5751.856203594902, 1895.3745264062827, 1942.873713208637, 1944.0171025553998, 1975.0914579293542, 1981.8810871347766, 26242.475150496884, 26260.73739262602, 26365.34086647849, 26393.868030659916, 26519.469610607874, 6764.594124352555, 6788.355106264897, 6926.411255043277, 6945.47908906834, 6966.582005079711, 16729.916350418414, 16736.99578025298, 16743.91628041852, 16756.16219189036, 16778.41275555374, 7494.6252687366505, 7948.682052717826, 7949.6025198739835, 8002.879956800536, 8017.198280171936, 7432.108147022392, 7440.776776696708, 7474.551526908294, 7552.358049565739, 7600.885986710204, 4678.929816052762, 5155.432902380735, 5322.600804939568, 5343.545798607084, 5345.599080068932, 1443.256722787765, 1499.7975273338177, 1528.8700460460764, 1540.1606124662087, 1542.282838480112, 892.9821403571892, 917.0554329041098, 917.9036201198778, 921.235374639048, 926.8795056642593, 7509.173990861001, 7566.621668916532, 7583.27958843909, 7584.165741768401, 7718.961405192938, 973.1582181413169, 993.7565296502403, 999.0558921818812, 1004.7638804880847, 1011.4437059617328, 4715.127701375257, 4781.0, 4787.952120478751, 4817.0365926814775, 4847.80608775645, 20899.0, 21735.782642173377, 21787.237446689607, 21797.0, 21813.78186218149, 21088.261910833353, 21882.671759923614, 21938.780612193674, 22018.889905550368, 22104.889475552518, 11292.096632269398, 11462.254953428, 11780.99861511764, 11796.115291353011, 11809.468573914195, 8474.703385381728, 8666.263367613823, 8726.127387261224, 8751.91248087511, 8787.384883058125, 18773.812261877207, 18932.242710291433, 19071.71392429087, 19307.710384344526, 19392.4182274532, 18840.905795470935, 19531.121099550837, 19574.706379404037, 19637.803621963598, 19672.90163549173, 12070.0, 12329.691757706129, 12342.81485777696, 12348.753024939631, 12354.382280886099, 14001.929990349983, 14019.439222431396, 14048.438062477386, 14136.363863626368, 14153.150810951376, 11326.603568875049, 11421.37182454973, 11544.884551154717, 11684.298942063504, 11848.28910265387, 11018.779624407627, 11225.270357426742, 11237.3257604544, 11301.43492825339, 11417.828732568853, 9763.414195148314, 9885.209183265179, 9912.256580756617, 9966.352187107816, 9977.002299769965, 7496.7376141834775, 7508.58702771331, 7534.924650753642, 7547.16981132064, 7593.1647518774, 6720.563163394364, 6750.2237242715755, 6792.490563207681, 6801.387875091265, 6837.042814005944], "value": 6731.727836801318, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.77793183937916, 503.629832073426, 527.3829619345365, 528.6378830501108, 530.6762874646466, 6422.068800024, 6449.677516124187, 6488.534356944367, 6490.156279683646, 6540.247871494779, 6100.359462256466, 6244.625322480654, 6324.0, 6364.777232796853, 6382.010788327805, 5126.026055049548, 5135.204043373283, 5135.6148288878485, 5146.897062058757, 5172.172452407625, 5037.370328708911, 5068.5438310552045, 5091.701616087892, 5093.719845408519, 5181.740912954356, 4921.286413470045, 5038.773255203506, 5043.495650434964, 5099.4390617032095, 5103.566196873252, 4992.950070499285, 5016.398032236137, 5090.10923088458, 5109.744512774347, 5121.723360993575, 4521.457425109008, 4536.183486972343, 4548.545145485458, 4587.678862479628, 4611.1930412177735, 6252.406021427957, 6267.9686601567155, 6281.685915704196, 6340.239171299428, 6360.395762402564, 5763.164341170507, 5783.797567085132, 5787.247657804501, 5836.5914385993, 5855.297364316267, 6614.371634694697, 6618.86762264752, 6727.159105111861, 6729.495287853381, 6808.863822723567, 4920.1635721927005, 4932.4081110267, 4940.456549779521, 4965.950340496585, 4980.203167493184, 7036.859262814766, 7045.401140902978, 7055.788326350218, 7171.426285897116, 7174.784756457315, 5033.2701758245585, 5035.899282014339, 5084.6440749147205, 5111.131107711698, 5126.410462796752, 5125.616083657466, 5127.53852153308, 5160.638755287169, 5161.974190129101, 5176.145935920531, 5775.971120144456, 5813.767449302065, 5850.619709718854, 5854.4730974212525, 5871.55963302754, 6591.967040164768, 6631.171103612049, 6744.629045402452, 6763.5265531413315, 6776.491763117786, 1695.6862980348676, 1709.6238827458187, 1711.3069206971197, 1741.4166254304696, 1786.5265704588294, 1212.078820096733, 1219.7316590350108, 1236.9876301236873, 1238.8761123887718, 1241.267652085274, 5841.853953651193, 5844.912326315109, 5858.47273745365, 5912.2406462797, 5973.729982606582, 6659.733610655519, 6768.56004359715, 6834.316568343225, 6840.213375461786, 6879.62652884754, 5894.440028197314, 5927.9703601481715, 5932.58471906971, 6049.85052839959, 6074.355386767904, 3652.470391793175, 3653.5615726112505, 3665.963340366562, 3689.6679298863232, 3691.3724666806706, 6666.400183873112, 6679.265280819106, 6747.291534388916, 6757.256701762803, 6758.391744742997, 7094.326039026284, 7100.715971361191, 7102.92897071033, 7167.89248161278, 7215.567065976058, 6613.503987200979, 6642.900356494656, 6678.766243181465, 6694.866102678016, 6726.663666816639, 6914.688839002358, 6954.0, 6959.251095817225, 6988.615626140611, 7021.6658834823265, 6830.556013859084, 6852.280510546321, 6873.2783057778215, 6881.518293719391, 6894.620795856276, 6488.188976377953, 6586.70359833799, 6606.6036037837885, 6616.503762217757, 6652.4345430637, 5017.448080711192, 5038.362532176984, 5065.240213967934, 5069.290299358019, 5102.08162530748, 6853.623050732058, 6936.0, 6941.514094013371, 6967.512274140761, 7005.719771209095, 6680.0, 6692.196936367667, 6709.161354830646, 6772.627505487246, 6776.525643204928, 6475.676216189266, 6637.137172167587, 6674.199096108498, 6689.431398331197, 6697.866042679217, 6415.294317625151, 6508.414242717989, 6522.021696745336, 6599.307072757291, 6703.4972377070935, 6575.24384695747, 6762.4928130389435, 6783.423409010092, 6801.727930882709, 6872.347127022925, 6474.449671777952, 6501.382368674968, 6507.446867016357, 6535.542512024303, 6584.275729669636], "value": 5169.00515794223, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [881.7531091294436, 904.1907492793946, 908.8136931928955, 908.8454962656348, 920.2637889688248, 18174.636507269846, 18981.050947452608, 19003.71494427583, 19020.90489547552, 21750.56498870022, 20306.18775248992, 20636.793632063676, 20837.166513339485, 20934.790652093514, 21015.2644657437, 924.7641851327902, 937.7046230437425, 937.95310234488, 938.5494962418044, 942.1661829281067, 959.4147569982305, 999.5651891427252, 1000.8999100090006, 1001.4892404873531, 1008.7629407089328, 956.0917128727701, 957.090763774417, 957.3490026781785, 962.8892677342126, 1027.1218108517223, 961.4952150121172, 961.8268711631904, 965.932384733069, 965.9951700241489, 1026.3533973596623, 698.1203683358954, 704.0460176460873, 704.2534912992194, 715.2811424518378, 741.9109706835214, 18419.0, 18502.2599096036, 19176.520586985298, 19424.320148794723, 19439.51401214967, 20686.965651717353, 20824.27115050966, 21210.257640982647, 21303.147874085018, 21363.145474181016, 17212.741808872877, 17288.827111728973, 17414.129293535272, 17415.3033878646, 17427.47717568475, 3671.6511931366745, 3708.295423869456, 3709.7959612221316, 3710.5176327077306, 3710.6474884885893, 129158.0, 129933.35033324867, 132319.33840330833, 133545.33227333898, 135556.0, 16080.839191608167, 16213.513594592181, 16271.83728162727, 16476.0, 16505.33978640865, 29251.414971700447, 30056.699433005826, 30063.248418789706, 30065.849670751508, 30117.247068823453, 7814.452988290879, 8055.194480551898, 8666.78333041679, 8993.955030224937, 9315.487648179414, 4173.290540608104, 4206.600372964564, 4266.573342665771, 4270.615644592001, 4276.037891474393, 5149.536541711264, 5214.504622060898, 5237.319148510669, 5252.553532949666, 5269.314989051475, 1081.1566977757361, 1092.1644941619668, 1102.415719668577, 1105.1545567640762, 1109.0573012938937, 701.7438634898225, 708.3553965891015, 714.3499415531841, 720.8450183210634, 728.2353528794745, 11290.661280161608, 11330.546778128948, 11407.543698252142, 11745.530178792924, 11859.762804743857, 686.6909890549273, 691.8754624167698, 694.3611877073105, 697.5291678117251, 698.6995591895534, 5562.193481945095, 5569.0, 5652.604317697722, 5679.204911312419, 5797.043487824546, 107985.0, 109581.45209273903, 109630.0, 110022.0, 110349.0, 94015.0, 95672.52163739137, 95896.0, 96041.0, 99053.0, 84378.15621843703, 84425.57787211148, 85705.0, 87328.0, 87585.5620721905, 10397.740056498647, 10406.843897341394, 10590.841137382791, 10679.5194216262, 10709.08972737326, 40559.59440405676, 41367.58632413638, 41450.585494144674, 41626.375604365356, 43019.56980430156, 71793.64103179451, 71806.28193717996, 74273.0, 75908.62045689736, 76236.61881690557, 17714.468565943043, 18150.45548633543, 18672.25310987545, 18806.05969701482, 18852.622947541247, 16335.918320408322, 16868.662626747642, 16961.830381696025, 19484.31804886879, 20583.176672932914, 18188.818111818713, 18793.0, 18824.905875470537, 19190.712139317642, 19474.513137171685, 17104.743428848327, 17150.742738859175, 18389.172487238375, 18630.161642725838, 18721.53196170107, 16526.917365413577, 16633.916830415772, 17868.374606888698, 18778.342758003404, 18840.811591883907, 12001.63995080149, 12067.275963442222, 12237.938810305892, 12563.87436125627, 12754.48982040708, 13470.595882123553, 13707.383167757675, 13749.243791591558, 14211.0, 14258.42966281337], "value": 9035.862045648968, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1480.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 100.0, 108.0, 106.0, 109.0, 107.0, 99.0, 110.0, 108.0], "value": 105.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 140.0, 182.0, 116.0, 146.0, 143.0, 117.0, 178.0, 124.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 161.0, 162.0, 162.0, 163.0, 166.0, 161.0, 162.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 181.0, 74.0, 74.0, 73.0, 73.0, 147.0, 74.0], "value": 91.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 131.0, 79.0, 79.0, 80.0, 142.0, 78.0, 79.0, 131.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0, 113.0, 113.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 61.0, 53.0, 52.0, 52.0, 53.0, 54.0, 52.0, 52.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 39.0, 38.0, 38.0, 38.0, 39.0, 38.0, 42.0, 38.0, 38.0], "value": 39.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 74.0, 73.0, 75.0, 75.0, 73.0, 74.0, 74.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 138.0, 121.0, 125.0, 116.0, 129.0, 125.0, 135.0, 124.0, 161.0], "value": 129.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 169.0, 167.0, 171.0, 172.0, 171.0, 170.0, 171.0, 170.0], "value": 170.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 63.0, 64.0, 63.0, 63.0, 63.0, 64.0, 61.0], "value": 63.4, "unit": "score"}]}]} 17:32:09 INFO - Return code: 0 17:32:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:32:10 INFO - # TBPL SUCCESS # 17:32:10 INFO - Running post-action listener: _package_coverage_data 17:32:10 INFO - Running post-action listener: _resource_record_post_action 17:32:10 INFO - [mozharness: 2017-09-01 00:32:10.057499Z] Finished run-tests step (success) 17:32:10 INFO - Running post-run listener: _resource_record_post_run 17:32:10 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 200704; Write bytes: 402292736; Read time: 68; Write time: 261392 17:32:10 INFO - TinderboxPrint: CPU usage
13.1% 17:32:10 INFO - TinderboxPrint: I/O read bytes / time
200,704 / 68 17:32:10 INFO - TinderboxPrint: I/O write bytes / time
402,292,736 / 261,392 17:32:10 INFO - TinderboxPrint: CPU idle
8,237.7 (86.9%) 17:32:10 INFO - TinderboxPrint: CPU user
1,193.2 (12.6%) 17:32:10 INFO - TinderboxPrint: Swap in / out
0 / 0 17:32:10 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 117387264; Read time: 0; Write time: 99616 17:32:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:32:10 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 200704; Write bytes: 284905472; Read time: 68; Write time: 161776 17:32:10 INFO - Running post-run listener: _upload_blobber_files 17:32:10 INFO - Blob upload gear active. 17:32:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:32:10 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 17:32:10 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'] 17:32:10 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 17:32:11 INFO - (blobuploader) - INFO - Open directory for files ... 17:32:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:32:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:32:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:32:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:32:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:32:12 INFO - (blobuploader) - INFO - Done attempting. 17:32:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:32:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:32:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:32:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:32:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:32:13 INFO - (blobuploader) - INFO - Done attempting. 17:32:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:32:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:32:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:32:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:32:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:32:20 INFO - (blobuploader) - INFO - Done attempting. 17:32:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:32:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:32:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:32:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:32:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:32:21 INFO - (blobuploader) - INFO - Done attempting. 17:32:21 INFO - (blobuploader) - INFO - Iteration through files over. 17:32:21 INFO - Return code: 0 17:32:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:32:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:32:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64fd83f4b9ec236a8ebde3d31f6e00d5c86a43b7dec9852db955b0bd0e9ff7d802899579809f526838ac5c66efd674767f6e878545691c9aa426171e2b259c25", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3d10f286899cb99fc7e080041943ece7d7c79586716297ef43085d9c471090cb358d747895803ac5632a34b282fd6cbbf12dd71ee312aef590e519db03a9b94", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5191820290d552ff5d554edb512593d0a2bfad2bb5ebb66c59a9001925ae73a1604e99449e2e48b1b87809648a73723dae6b1436bb35fff34797c018bbae7acb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d3f39e38df966a0f0a4af8ce26bebf9327891bd26814ff2b9c07c7ac343c592c89f8a758b95f2460ea909129bf8799ff08edc3f4c38066d33160bec6dd08ef1"} 17:32:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:32:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:32:21 INFO - Contents: 17:32:21 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64fd83f4b9ec236a8ebde3d31f6e00d5c86a43b7dec9852db955b0bd0e9ff7d802899579809f526838ac5c66efd674767f6e878545691c9aa426171e2b259c25", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3d10f286899cb99fc7e080041943ece7d7c79586716297ef43085d9c471090cb358d747895803ac5632a34b282fd6cbbf12dd71ee312aef590e519db03a9b94", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5191820290d552ff5d554edb512593d0a2bfad2bb5ebb66c59a9001925ae73a1604e99449e2e48b1b87809648a73723dae6b1436bb35fff34797c018bbae7acb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d3f39e38df966a0f0a4af8ce26bebf9327891bd26814ff2b9c07c7ac343c592c89f8a758b95f2460ea909129bf8799ff08edc3f4c38066d33160bec6dd08ef1"} 17:32:21 INFO - Running post-run listener: copy_logs_to_upload_dir 17:32:21 INFO - Copying logs to upload dir... 17:32:21 INFO - mkdir: /builds/slave/test/build/upload/logs 17:32:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.963902 ========= master_lag: 0.22 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-08-31 17:32:21.899478) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-31 17:32:21.904092) ========= 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=826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64fd83f4b9ec236a8ebde3d31f6e00d5c86a43b7dec9852db955b0bd0e9ff7d802899579809f526838ac5c66efd674767f6e878545691c9aa426171e2b259c25", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3d10f286899cb99fc7e080041943ece7d7c79586716297ef43085d9c471090cb358d747895803ac5632a34b282fd6cbbf12dd71ee312aef590e519db03a9b94", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5191820290d552ff5d554edb512593d0a2bfad2bb5ebb66c59a9001925ae73a1604e99449e2e48b1b87809648a73723dae6b1436bb35fff34797c018bbae7acb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d3f39e38df966a0f0a4af8ce26bebf9327891bd26814ff2b9c07c7ac343c592c89f8a758b95f2460ea909129bf8799ff08edc3f4c38066d33160bec6dd08ef1"} build_url:https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.009749 build_url: 'https://queue.taskcluster.net/v1/task/VwskOH_MSgKsTQf61Zi0cg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64fd83f4b9ec236a8ebde3d31f6e00d5c86a43b7dec9852db955b0bd0e9ff7d802899579809f526838ac5c66efd674767f6e878545691c9aa426171e2b259c25", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3d10f286899cb99fc7e080041943ece7d7c79586716297ef43085d9c471090cb358d747895803ac5632a34b282fd6cbbf12dd71ee312aef590e519db03a9b94", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5191820290d552ff5d554edb512593d0a2bfad2bb5ebb66c59a9001925ae73a1604e99449e2e48b1b87809648a73723dae6b1436bb35fff34797c018bbae7acb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d3f39e38df966a0f0a4af8ce26bebf9327891bd26814ff2b9c07c7ac343c592c89f8a758b95f2460ea909129bf8799ff08edc3f4c38066d33160bec6dd08ef1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-31 17:32:21.933435) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 17:32:21.939864) ========= 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=826874ccc97bd7bf541b4875000001d5-1504222161.336578-1890537695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 17:32:21.989675) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-31 17:32:21.989989) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-31 17:32:21.990280) ========= ========= Total master_lag: 0.44 =========