builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-058 starttime: 1503701140.47 results: success (0) revision: bb813dab9832c1b780293edbc5477f920bf0c88e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:40.470917) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:40.474658) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:40.475173) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:40.493194) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:40.493574) ========= 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=0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-25 15:45:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 25.4M=0s 2017-08-25 15:45:40 (25.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146411 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:40.656979) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:40.657520) ========= 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=0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019578 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:40.707661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-25 15:45:40.708037) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bb813dab9832c1b780293edbc5477f920bf0c88e --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 bb813dab9832c1b780293edbc5477f920bf0c88e --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=0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-25 15:45:40,774 truncating revision to first 12 chars 2017-08-25 15:45:40,774 Setting DEBUG logging. 2017-08-25 15:45:40,775 attempt 1/10 2017-08-25 15:45:40,775 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bb813dab9832?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-25 15:45:41,796 unpacking tar archive at: mozilla-central-bb813dab9832/testing/mozharness/ program finished with exit code 0 elapsedTime=1.338941 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-25 15:45:42.061516) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:42.061841) ========= script_repo_revision: bb813dab9832c1b780293edbc5477f920bf0c88e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:42.062196) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:42.062453) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 15:45:42.082232) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-08-25 15:45:42.082588) ========= /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=0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:45:42 INFO - MultiFileLogger online at 20170825 15:45:42 in /builds/slave/test 15:45:42 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 15:45:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:45:42 INFO - {'append_to_log': False, 15:45:42 INFO - 'base_work_dir': '/builds/slave/test', 15:45:42 INFO - 'blob_upload_branch': 'Firefox', 15:45:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:45:42 INFO - 'branch': 'Firefox', 15:45:42 INFO - 'buildbot_json_path': 'buildprops.json', 15:45:42 INFO - 'code_coverage': False, 15:45:42 INFO - 'config_files': ('talos/linux_config.py',), 15:45:42 INFO - 'default_actions': ('clobber', 15:45:42 INFO - 'read-buildbot-config', 15:45:42 INFO - 'download-and-extract', 15:45:42 INFO - 'populate-webroot', 15:45:42 INFO - 'create-virtualenv', 15:45:42 INFO - 'install', 15:45:42 INFO - 'setup-mitmproxy', 15:45:42 INFO - 'run-tests'), 15:45:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:45:42 INFO - 'disable_ccov_upload': False, 15:45:42 INFO - 'download_minidump_stackwalk': True, 15:45:42 INFO - 'download_symbols': 'ondemand', 15:45:42 INFO - 'enable_stylo': False, 15:45:42 INFO - 'enable_webrender': False, 15:45:42 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:45:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:45:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:45:42 INFO - 'gecko_profile': False, 15:45:42 INFO - 'gecko_profile_interval': 0, 15:45:42 INFO - 'installer_path': 'installer.exe', 15:45:42 INFO - 'log_level': 'info', 15:45:42 INFO - 'log_name': 'talos', 15:45:42 INFO - 'log_to_console': True, 15:45:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:45:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:45:42 INFO - 'opt_config_files': (), 15:45:42 INFO - 'pip_index': False, 15:45:42 INFO - 'suite': 'dromaeojs-e10s', 15:45:42 INFO - 'system_bits': '32', 15:45:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:45:42 INFO - 'title': 'talos-linux64-ix-058', 15:45:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:45:42 INFO - 'use_talos_json': True, 15:45:42 INFO - 'verify': 'False', 15:45:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:45:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:45:42 INFO - 'work_dir': 'build'} 15:45:42 INFO - [mozharness: 2017-08-25 22:45:42.282060Z] Running clobber step. 15:45:42 INFO - Running pre-action listener: _resource_record_pre_action 15:45:42 INFO - Running main action method: clobber 15:45:42 INFO - rmtree: /builds/slave/test/build 15:45:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:45:43 INFO - Running post-action listener: _resource_record_post_action 15:45:43 INFO - [mozharness: 2017-08-25 22:45:43.871055Z] Finished clobber step (success) 15:45:43 INFO - [mozharness: 2017-08-25 22:45:43.871152Z] Running read-buildbot-config step. 15:45:43 INFO - Running pre-action listener: _resource_record_pre_action 15:45:43 INFO - Running main action method: read_buildbot_config 15:45:43 INFO - Using buildbot properties: 15:45:43 INFO - { 15:45:43 INFO - "project": "", 15:45:43 INFO - "product": "firefox", 15:45:43 INFO - "who": "ffxbld@noreply.mozilla.org", 15:45:43 INFO - "installer_path": "public/build/target.tar.bz2", 15:45:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 15:45:43 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:45:43 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 15:45:43 INFO - "stage_platform": "linux64-qr", 15:45:43 INFO - "basedir": "/builds/slave/test", 15:45:43 INFO - "buildnumber": 15, 15:45:43 INFO - "platform": "ubuntu64_hw_qr", 15:45:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:45:43 INFO - "slavebuilddir": "test", 15:45:43 INFO - "taskId": "dlLCbtTZTAO-l0GravaY8g", 15:45:43 INFO - "branch": "mozilla-central", 15:45:43 INFO - "script_repo_revision": "production", 15:45:43 INFO - "revision": "bb813dab9832c1b780293edbc5477f920bf0c88e", 15:45:43 INFO - "slavename": "talos-linux64-ix-058", 15:45:43 INFO - "repo_path": "mozilla-central" 15:45:43 INFO - } 15:45:43 INFO - Finding installer, test and symbols from parent task. 15:45:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dlLCbtTZTAO-l0GravaY8g'}, attempt #1 15:45:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dlLCbtTZTAO-l0GravaY8g'}, attempt #1 15:45:44 INFO - Task dependencies: dZXUCCYSRt2PJV-HC3Hz2w 15:45:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w'}, attempt #1 15:45:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 15:45:45 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.test_packages.json 15:45:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.crashreporter-symbols.zip 15:45:45 INFO - Running post-action listener: _resource_record_post_action 15:45:45 INFO - [mozharness: 2017-08-25 22:45:45.252350Z] Finished read-buildbot-config step (success) 15:45:45 INFO - [mozharness: 2017-08-25 22:45:45.252565Z] Running download-and-extract step. 15:45:45 INFO - Running pre-action listener: _resource_record_pre_action 15:45:45 INFO - Running main action method: download_and_extract 15:45:45 INFO - mkdir: /builds/slave/test/build/tests 15:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:45 INFO - trying https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.test_packages.json 15:45:45 INFO - Downloading https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:45:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:45:45 INFO - Downloaded 989 bytes. 15:45:45 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:45:45 INFO - Using the following test package requirements: 15:45:45 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:45:45 INFO - u'common': [u'target.common.tests.zip'], 15:45:45 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:45:45 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:45:45 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:45:45 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:45:45 INFO - u'mozbase': [u'target.common.tests.zip'], 15:45:45 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:45:45 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:45:45 INFO - u'web-platform': [u'target.common.tests.zip', 15:45:45 INFO - u'target.web-platform.tests.tar.gz'], 15:45:45 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:45:45 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:45:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip 15:45:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:45:45 INFO - Fetch https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip into memory 15:45:47 INFO - Content-Length response header: 54979320 15:45:47 INFO - Bytes received: 54979320 15:45:58 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:45:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip 15:45:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:45:58 INFO - Fetch https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip into memory 15:45:59 INFO - Content-Length response header: 54979320 15:45:59 INFO - Bytes received: 54979320 15:46:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.talos.tests.zip 15:46:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:46:11 INFO - Fetch https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.talos.tests.zip into memory 15:46:12 INFO - Content-Length response header: 14496611 15:46:12 INFO - Bytes received: 14496611 15:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:12 INFO - trying https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 15:46:12 INFO - Downloading https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:46:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:46:14 INFO - Downloaded 61183659 bytes. 15:46:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 15:46:14 INFO - mkdir: /builds/slave/test/properties 15:46:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:46:14 INFO - Writing to file /builds/slave/test/properties/build_url 15:46:14 INFO - Contents: 15:46:14 INFO - build_url:https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 15:46:14 INFO - Running post-action listener: _resource_record_post_action 15:46:14 INFO - Running post-action listener: find_tests_for_verification 15:46:14 INFO - Running post-action listener: set_extra_try_arguments 15:46:14 INFO - [mozharness: 2017-08-25 22:46:14.915352Z] Finished download-and-extract step (success) 15:46:14 INFO - [mozharness: 2017-08-25 22:46:14.915587Z] Running populate-webroot step. 15:46:14 INFO - Running pre-action listener: _resource_record_pre_action 15:46:14 INFO - Running main action method: populate_webroot 15:46:14 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:46:14 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:46:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:46:14 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:46:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:46:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:14 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:14 INFO - 'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:46:14 INFO - 'tests': ['damp', 'tps']}, 15:46:14 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:14 INFO - 'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['damp', 'tps']}, 15:46:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:46:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:46:14 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:46:14 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:46:14 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:46:14 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:14 INFO - 'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:46:14 INFO - 'other-e10s': {'tests': ['a11yr', 15:46:14 INFO - 'ts_paint', 15:46:14 INFO - 'tpaint', 15:46:14 INFO - 'sessionrestore', 15:46:14 INFO - 'sessionrestore_many_windows', 15:46:14 INFO - 'sessionrestore_no_auto_restore', 15:46:14 INFO - 'tabpaint']}, 15:46:14 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['a11yr', 15:46:14 INFO - 'ts_paint', 15:46:14 INFO - 'tpaint', 15:46:14 INFO - 'sessionrestore', 15:46:14 INFO - 'sessionrestore_many_windows', 15:46:14 INFO - 'sessionrestore_no_auto_restore', 15:46:14 INFO - 'tabpaint']}, 15:46:14 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:46:14 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:46:14 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['bloom_basic_singleton']}, 15:46:14 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['bloom_basic']}, 15:46:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:46:14 INFO - 'tsvgr_opacity', 15:46:14 INFO - 'tart', 15:46:14 INFO - 'tscrollx', 15:46:14 INFO - 'tsvg_static']}, 15:46:14 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['tsvgx', 15:46:14 INFO - 'tsvgr_opacity', 15:46:14 INFO - 'tart', 15:46:14 INFO - 'tscrollx', 15:46:14 INFO - 'tsvg_static']}, 15:46:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:46:14 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:14 INFO - 'talos_options': ['--stylo'], 15:46:14 INFO - 'tests': ['tp5o']}, 15:46:14 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:14 INFO - 'talos_options': ['--mitmproxy', 15:46:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:14 INFO - '--firstNonBlankPaint'], 15:46:14 INFO - 'tests': ['tp6_google', 15:46:14 INFO - 'tp6_youtube', 15:46:14 INFO - 'tp6_amazon', 15:46:14 INFO - 'tp6_facebook']}, 15:46:14 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:14 INFO - 'talos_options': ['--stylo', 15:46:14 INFO - '--mitmproxy', 15:46:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:14 INFO - '--firstNonBlankPaint'], 15:46:14 INFO - 'tests': ['tp6_google', 15:46:14 INFO - 'tp6_youtube', 15:46:14 INFO - 'tp6_amazon', 15:46:14 INFO - 'tp6_facebook']}, 15:46:14 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:14 INFO - 'talos_options': ['--stylo', 15:46:14 INFO - '--stylo-threads=1', 15:46:14 INFO - '--mitmproxy', 15:46:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:14 INFO - '--firstNonBlankPaint'], 15:46:14 INFO - 'tests': ['tp6_google', 15:46:14 INFO - 'tp6_youtube', 15:46:14 INFO - 'tp6_amazon', 15:46:14 INFO - 'tp6_facebook']}, 15:46:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:46:14 INFO - 'talos_options': ['--xperf_path', 15:46:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:14 INFO - 'tests': ['tp5n']}, 15:46:14 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:14 INFO - 'talos_options': ['--stylo', 15:46:14 INFO - '--xperf_path', 15:46:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:14 INFO - 'tests': ['tp5n']}}} 15:46:14 INFO - Running post-action listener: _resource_record_post_action 15:46:14 INFO - [mozharness: 2017-08-25 22:46:14.939834Z] Finished populate-webroot step (success) 15:46:14 INFO - [mozharness: 2017-08-25 22:46:14.939923Z] Running create-virtualenv step. 15:46:14 INFO - Running pre-action listener: _resource_record_pre_action 15:46:14 INFO - Running main action method: create_virtualenv 15:46:14 INFO - Creating virtualenv /builds/slave/test/build/venv 15:46:14 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:46:14 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:46:14 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:46:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:46:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:46:15 INFO - Installing distribute.............................................................................................................................................................................................done. 15:46:15 INFO - Installing pip...............done. 15:46:15 INFO - Return code: 0 15:46:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:46:15 INFO - Reading from file tmpfile_stdout 15:46:15 INFO - Output received: 15:46:15 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:46:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, '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': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', '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 15:46:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:46:15 INFO - Using env: {'DISPLAY': ':0', 15:46:15 INFO - 'HOME': '/home/cltbld', 15:46:15 INFO - 'LANG': 'en_US.UTF-8', 15:46:15 INFO - 'LANGUAGE': 'en_US:en', 15:46:15 INFO - 'LOGNAME': 'cltbld', 15:46:15 INFO - 'MAIL': '/var/mail/cltbld', 15:46:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:15 INFO - 'MOZ_NO_REMOTE': '1', 15:46:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:15 INFO - 'NO_EM_RESTART': '1', 15:46:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:15 INFO - 'PWD': '/builds/slave/test', 15:46:15 INFO - 'SHELL': '/bin/bash', 15:46:15 INFO - 'SHLVL': '1', 15:46:15 INFO - 'TERM': 'linux', 15:46:15 INFO - 'TMOUT': '86400', 15:46:15 INFO - 'USER': 'cltbld', 15:46:15 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:15 INFO - '_': '/tools/buildbot/bin/python'} 15:46:17 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:46:17 INFO - Downloading/unpacking pip>=1.5 15:46:17 INFO - Running setup.py egg_info for package pip 15:46:17 INFO - warning: no files found matching 'pip/cacert.pem' 15:46:17 INFO - warning: no files found matching '*.html' under directory 'docs' 15:46:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:46:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:46:17 INFO - Installing collected packages: pip 15:46:17 INFO - Found existing installation: pip 1.1 15:46:17 INFO - Uninstalling pip: 15:46:17 INFO - Successfully uninstalled pip 15:46:17 INFO - Running setup.py install for pip 15:46:17 INFO - warning: no files found matching 'pip/cacert.pem' 15:46:17 INFO - warning: no files found matching '*.html' under directory 'docs' 15:46:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:46:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:46:17 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:46:17 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:46:17 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:46:17 INFO - Successfully installed pip 15:46:17 INFO - Cleaning up... 15:46:17 INFO - Return code: 0 15:46:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:46: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')]} 15:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46: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')]} 15:46:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, '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': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', '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 15:46:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:46:17 INFO - Using env: {'DISPLAY': ':0', 15:46:17 INFO - 'HOME': '/home/cltbld', 15:46:17 INFO - 'LANG': 'en_US.UTF-8', 15:46:17 INFO - 'LANGUAGE': 'en_US:en', 15:46:17 INFO - 'LOGNAME': 'cltbld', 15:46:17 INFO - 'MAIL': '/var/mail/cltbld', 15:46:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:17 INFO - 'MOZ_NO_REMOTE': '1', 15:46:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:17 INFO - 'NO_EM_RESTART': '1', 15:46:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:17 INFO - 'PWD': '/builds/slave/test', 15:46:17 INFO - 'SHELL': '/bin/bash', 15:46:17 INFO - 'SHLVL': '1', 15:46:17 INFO - 'TERM': 'linux', 15:46:17 INFO - 'TMOUT': '86400', 15:46:17 INFO - 'USER': 'cltbld', 15:46:17 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:17 INFO - '_': '/tools/buildbot/bin/python'} 15:46:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:17 INFO - Downloading/unpacking psutil>=3.1.1 15:46: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 15:46: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 15:46: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 15:46: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 15:46:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:46:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:18 INFO - Installing collected packages: psutil 15:46:18 INFO - Running setup.py install for psutil 15:46:18 INFO - building 'psutil._psutil_linux' extension 15:46:18 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 15:46:18 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 15:46:18 INFO - building 'psutil._psutil_posix' extension 15:46:18 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 15:46:18 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 15:46:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:19 INFO - Successfully installed psutil 15:46:19 INFO - Cleaning up... 15:46:19 INFO - Return code: 0 15:46:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:46: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')]} 15:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46: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')]} 15:46:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, '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': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', '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 15:46: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:46: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 mozsystemmonitor==0.3 15:46:19 INFO - Using env: {'DISPLAY': ':0', 15:46:19 INFO - 'HOME': '/home/cltbld', 15:46:19 INFO - 'LANG': 'en_US.UTF-8', 15:46:19 INFO - 'LANGUAGE': 'en_US:en', 15:46:19 INFO - 'LOGNAME': 'cltbld', 15:46:19 INFO - 'MAIL': '/var/mail/cltbld', 15:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:19 INFO - 'MOZ_NO_REMOTE': '1', 15:46:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:19 INFO - 'NO_EM_RESTART': '1', 15:46:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:19 INFO - 'PWD': '/builds/slave/test', 15:46:19 INFO - 'SHELL': '/bin/bash', 15:46:19 INFO - 'SHLVL': '1', 15:46:19 INFO - 'TERM': 'linux', 15:46:19 INFO - 'TMOUT': '86400', 15:46:19 INFO - 'USER': 'cltbld', 15:46:19 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:19 INFO - '_': '/tools/buildbot/bin/python'} 15:46:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:46:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:46:20 INFO - Installing collected packages: mozsystemmonitor 15:46:20 INFO - Running setup.py install for mozsystemmonitor 15:46:20 INFO - Successfully installed mozsystemmonitor 15:46:20 INFO - Cleaning up... 15:46:20 INFO - Return code: 0 15:46:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:20 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 0x19891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, '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': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', '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 15:46:20 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 15:46:20 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 15:46:20 INFO - Using env: {'DISPLAY': ':0', 15:46:20 INFO - 'HOME': '/home/cltbld', 15:46:20 INFO - 'LANG': 'en_US.UTF-8', 15:46:20 INFO - 'LANGUAGE': 'en_US:en', 15:46:20 INFO - 'LOGNAME': 'cltbld', 15:46:20 INFO - 'MAIL': '/var/mail/cltbld', 15:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:20 INFO - 'MOZ_NO_REMOTE': '1', 15:46:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:20 INFO - 'NO_EM_RESTART': '1', 15:46:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:20 INFO - 'PWD': '/builds/slave/test', 15:46:20 INFO - 'SHELL': '/bin/bash', 15:46:20 INFO - 'SHLVL': '1', 15:46:20 INFO - 'TERM': 'linux', 15:46:20 INFO - 'TMOUT': '86400', 15:46:20 INFO - 'USER': 'cltbld', 15:46:20 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:20 INFO - '_': '/tools/buildbot/bin/python'} 15:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:20 INFO - Downloading/unpacking jsonschema==2.5.1 15:46:20 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 15:46:20 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 15:46:20 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 15:46:20 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 15:46:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:46:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:46:21 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 15:46:21 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 15:46:21 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 15:46:21 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 15:46:22 INFO - Downloading functools32-3.2.3-2.tar.gz 15:46:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:46:22 INFO - warning: no files found matching '*.txt' 15:46:22 INFO - no previously-included directories found matching 'build' 15:46:22 INFO - no previously-included directories found matching 'dist' 15:46:22 INFO - no previously-included directories found matching '.git*' 15:46:22 INFO - Installing collected packages: jsonschema, functools32 15:46:22 INFO - Running setup.py install for functools32 15:46:22 INFO - warning: no files found matching '*.txt' 15:46:22 INFO - no previously-included directories found matching 'build' 15:46:22 INFO - no previously-included directories found matching 'dist' 15:46:22 INFO - no previously-included directories found matching '.git*' 15:46:22 INFO - Successfully installed jsonschema functools32 15:46:22 INFO - Cleaning up... 15:46:22 INFO - Return code: 0 15:46:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:46:22 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')]} 15:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:22 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')]} 15:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:22 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 0x19891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, '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': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', '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 15:46:22 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 15:46:22 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 15:46:22 INFO - Using env: {'DISPLAY': ':0', 15:46:22 INFO - 'HOME': '/home/cltbld', 15:46:22 INFO - 'LANG': 'en_US.UTF-8', 15:46:22 INFO - 'LANGUAGE': 'en_US:en', 15:46:22 INFO - 'LOGNAME': 'cltbld', 15:46:22 INFO - 'MAIL': '/var/mail/cltbld', 15:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:22 INFO - 'MOZ_NO_REMOTE': '1', 15:46:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:22 INFO - 'NO_EM_RESTART': '1', 15:46:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:22 INFO - 'PWD': '/builds/slave/test', 15:46:22 INFO - 'SHELL': '/bin/bash', 15:46:22 INFO - 'SHLVL': '1', 15:46:22 INFO - 'TERM': 'linux', 15:46:22 INFO - 'TMOUT': '86400', 15:46:22 INFO - 'USER': 'cltbld', 15:46:22 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:22 INFO - '_': '/tools/buildbot/bin/python'} 15:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:46:22 INFO - Cleaning up... 15:46:22 INFO - Return code: 0 15:46:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:46:22 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')]} 15:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:22 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')]} 15:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:22 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 0x19891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, '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': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', '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 15:46:22 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 15:46:22 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 15:46:22 INFO - Using env: {'DISPLAY': ':0', 15:46:22 INFO - 'HOME': '/home/cltbld', 15:46:22 INFO - 'LANG': 'en_US.UTF-8', 15:46:22 INFO - 'LANGUAGE': 'en_US:en', 15:46:22 INFO - 'LOGNAME': 'cltbld', 15:46:22 INFO - 'MAIL': '/var/mail/cltbld', 15:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:22 INFO - 'MOZ_NO_REMOTE': '1', 15:46:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:22 INFO - 'NO_EM_RESTART': '1', 15:46:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:22 INFO - 'PWD': '/builds/slave/test', 15:46:22 INFO - 'SHELL': '/bin/bash', 15:46:22 INFO - 'SHLVL': '1', 15:46:22 INFO - 'TERM': 'linux', 15:46:22 INFO - 'TMOUT': '86400', 15:46:22 INFO - 'USER': 'cltbld', 15:46:22 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:22 INFO - '_': '/tools/buildbot/bin/python'} 15:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:22 INFO - Downloading/unpacking blobuploader==1.2.4 15:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:23 INFO - Downloading blobuploader-1.2.4.tar.gz 15:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:46:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:46:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:24 INFO - Downloading docopt-0.6.1.tar.gz 15:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:46:24 INFO - Installing collected packages: blobuploader, requests, docopt 15:46:24 INFO - Running setup.py install for blobuploader 15:46:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:46:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:46:24 INFO - Running setup.py install for requests 15:46:25 INFO - Running setup.py install for docopt 15:46:25 INFO - Successfully installed blobuploader requests docopt 15:46:25 INFO - Cleaning up... 15:46:25 INFO - Return code: 0 15:46:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46:25 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')]} 15:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:25 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')]} 15:46:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:25 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 0x19891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, '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': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', '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 15:46:25 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 15:46:25 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 15:46:25 INFO - Using env: {'DISPLAY': ':0', 15:46:25 INFO - 'HOME': '/home/cltbld', 15:46:25 INFO - 'LANG': 'en_US.UTF-8', 15:46:25 INFO - 'LANGUAGE': 'en_US:en', 15:46:25 INFO - 'LOGNAME': 'cltbld', 15:46:25 INFO - 'MAIL': '/var/mail/cltbld', 15:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:25 INFO - 'MOZ_NO_REMOTE': '1', 15:46:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:25 INFO - 'NO_EM_RESTART': '1', 15:46:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:25 INFO - 'PWD': '/builds/slave/test', 15:46:25 INFO - 'SHELL': '/bin/bash', 15:46:25 INFO - 'SHLVL': '1', 15:46:25 INFO - 'TERM': 'linux', 15:46:25 INFO - 'TMOUT': '86400', 15:46:25 INFO - 'USER': 'cltbld', 15:46:25 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:25 INFO - '_': '/tools/buildbot/bin/python'} 15:46:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:46:25 INFO - Running setup.py (path:/tmp/pip-NfdjvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:46:25 INFO - Running setup.py (path:/tmp/pip-OvCiBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:46:25 INFO - Running setup.py (path:/tmp/pip-rGCTB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:46:25 INFO - Running setup.py (path:/tmp/pip-ECjPWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:46:25 INFO - Running setup.py (path:/tmp/pip-dcoLge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:46:25 INFO - Running setup.py (path:/tmp/pip-YD9gQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:46:25 INFO - Running setup.py (path:/tmp/pip-rJvqdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:46:26 INFO - Running setup.py (path:/tmp/pip-ysmgJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:46:26 INFO - Running setup.py (path:/tmp/pip-UKSl3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:46:26 INFO - Running setup.py (path:/tmp/pip-mmjD7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:46:26 INFO - Running setup.py (path:/tmp/pip-2FHd74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:46:26 INFO - Running setup.py (path:/tmp/pip-9C_IBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:46:26 INFO - Running setup.py (path:/tmp/pip-qUBU19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:46:26 INFO - Running setup.py (path:/tmp/pip-3_yQbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:26 INFO - Running setup.py (path:/tmp/pip-D7EhxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:46:26 INFO - Running setup.py (path:/tmp/pip-nRQADW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:46:26 INFO - Running setup.py (path:/tmp/pip-EZXrho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:46:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:46:26 INFO - Running setup.py install for manifestparser 15:46:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:46:26 INFO - Running setup.py install for mozcrash 15:46:26 INFO - Running setup.py install for mozdebug 15:46:27 INFO - Running setup.py install for mozdevice 15:46:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:46:27 INFO - Running setup.py install for mozfile 15:46:27 INFO - Running setup.py install for mozhttpd 15:46:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:46:27 INFO - Running setup.py install for mozinfo 15:46:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:46:27 INFO - Running setup.py install for mozInstall 15:46:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:46:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:46:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:46:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:46:27 INFO - Running setup.py install for mozleak 15:46:27 INFO - Running setup.py install for mozlog 15:46:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:46:27 INFO - Running setup.py install for moznetwork 15:46:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:46:27 INFO - Running setup.py install for mozprocess 15:46:27 INFO - Running setup.py install for mozprofile 15:46:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:46:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:46:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:46:28 INFO - Running setup.py install for mozrunner 15:46:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:46:28 INFO - Running setup.py install for mozscreenshot 15:46:28 INFO - Running setup.py install for moztest 15:46:28 INFO - Running setup.py install for mozversion 15:46:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:46:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:46:28 INFO - Cleaning up... 15:46:28 INFO - Return code: 0 15:46:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:28 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 0x19891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, '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': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', '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 15:46:28 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 15:46:28 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 15:46:28 INFO - Using env: {'DISPLAY': ':0', 15:46:28 INFO - 'HOME': '/home/cltbld', 15:46:28 INFO - 'LANG': 'en_US.UTF-8', 15:46:28 INFO - 'LANGUAGE': 'en_US:en', 15:46:28 INFO - 'LOGNAME': 'cltbld', 15:46:28 INFO - 'MAIL': '/var/mail/cltbld', 15:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:28 INFO - 'MOZ_NO_REMOTE': '1', 15:46:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:28 INFO - 'NO_EM_RESTART': '1', 15:46:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:28 INFO - 'PWD': '/builds/slave/test', 15:46:28 INFO - 'SHELL': '/bin/bash', 15:46:28 INFO - 'SHLVL': '1', 15:46:28 INFO - 'TERM': 'linux', 15:46:28 INFO - 'TMOUT': '86400', 15:46:28 INFO - 'USER': 'cltbld', 15:46:28 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:28 INFO - '_': '/tools/buildbot/bin/python'} 15:46:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:46:28 INFO - Running setup.py (path:/tmp/pip-lJzoNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:46:28 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)) 15:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:46:28 INFO - Running setup.py (path:/tmp/pip-2nJ8Lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:46:28 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)) 15:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:46:28 INFO - Running setup.py (path:/tmp/pip-Y6n_i_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:46:28 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)) 15:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:46:28 INFO - Running setup.py (path:/tmp/pip-0iZuLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:46:28 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)) 15:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:46:28 INFO - Running setup.py (path:/tmp/pip-UlyTje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:46:29 INFO - Running setup.py (path:/tmp/pip-DGlzXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:46:29 INFO - Running setup.py (path:/tmp/pip-vwlwOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:46:29 INFO - Running setup.py (path:/tmp/pip-kIahYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:46:29 INFO - Running setup.py (path:/tmp/pip-KLBwg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:46:29 INFO - Running setup.py (path:/tmp/pip-hgZ2kV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:46:29 INFO - Running setup.py (path:/tmp/pip-3JWQ3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:46:29 INFO - Running setup.py (path:/tmp/pip-K9Jvqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:46:29 INFO - Running setup.py (path:/tmp/pip-PpA3KS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:46:29 INFO - Running setup.py (path:/tmp/pip-aAa6nJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:29 INFO - Running setup.py (path:/tmp/pip-imZ67K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:46:29 INFO - Running setup.py (path:/tmp/pip-afLdcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:46:29 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)) 15:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:46:29 INFO - Running setup.py (path:/tmp/pip-q5fRqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:46:29 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)) 15:46:29 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)) 15:46:29 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)) 15:46:29 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)) 15:46:29 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)) 15:46:29 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)) 15:46:29 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)) 15:46:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:46:29 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 15:46:29 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 15:46:29 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 15:46:29 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 15:46:31 INFO - Downloading blessings-1.6.tar.gz 15:46:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:46:31 INFO - Installing collected packages: blessings 15:46:31 INFO - Running setup.py install for blessings 15:46:31 INFO - Successfully installed blessings 15:46:31 INFO - Cleaning up... 15:46:31 INFO - Return code: 0 15:46:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:46:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:46:31 INFO - Reading from file tmpfile_stdout 15:46:31 INFO - Current package versions: 15:46:31 INFO - argparse == 1.2.1 15:46:31 INFO - blessings == 1.6 15:46:31 INFO - blobuploader == 1.2.4 15:46:31 INFO - docopt == 0.6.1 15:46:31 INFO - functools32 == 3.2.3-2 15:46:31 INFO - jsonschema == 2.5.1 15:46:31 INFO - manifestparser == 1.1 15:46:31 INFO - mozInstall == 1.13 15:46:31 INFO - mozcrash == 1.0 15:46:31 INFO - mozdebug == 0.1 15:46:31 INFO - mozdevice == 0.51 15:46:31 INFO - mozfile == 1.2 15:46:31 INFO - mozhttpd == 0.7 15:46:31 INFO - mozinfo == 0.10 15:46:31 INFO - mozleak == 0.1 15:46:31 INFO - mozlog == 3.5 15:46:31 INFO - moznetwork == 0.27 15:46:31 INFO - mozprocess == 0.25 15:46:31 INFO - mozprofile == 0.28 15:46:31 INFO - mozrunner == 6.13 15:46:31 INFO - mozscreenshot == 0.1 15:46:31 INFO - mozsystemmonitor == 0.3 15:46:31 INFO - moztest == 0.8 15:46:31 INFO - mozversion == 1.4 15:46:31 INFO - psutil == 3.1.1 15:46:31 INFO - requests == 1.2.3 15:46:31 INFO - wsgiref == 0.1.2 15:46:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46: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')]} 15:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46: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')]} 15:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:31 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 0x19891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, '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': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', '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 15:46:31 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 15:46:31 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 15:46:31 INFO - Using env: {'DISPLAY': ':0', 15:46:31 INFO - 'HOME': '/home/cltbld', 15:46:31 INFO - 'LANG': 'en_US.UTF-8', 15:46:31 INFO - 'LANGUAGE': 'en_US:en', 15:46:31 INFO - 'LOGNAME': 'cltbld', 15:46:31 INFO - 'MAIL': '/var/mail/cltbld', 15:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:31 INFO - 'MOZ_NO_REMOTE': '1', 15:46:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:31 INFO - 'NO_EM_RESTART': '1', 15:46:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:31 INFO - 'PWD': '/builds/slave/test', 15:46:31 INFO - 'SHELL': '/bin/bash', 15:46:31 INFO - 'SHLVL': '1', 15:46:31 INFO - 'TERM': 'linux', 15:46:31 INFO - 'TMOUT': '86400', 15:46:31 INFO - 'USER': 'cltbld', 15:46:31 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:31 INFO - '_': '/tools/buildbot/bin/python'} 15:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:31 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)) 15:46:31 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)) 15:46:31 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)) 15:46:31 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)) 15:46:31 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)) 15:46:31 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)) 15:46:31 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)) 15:46:31 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)) 15:46:31 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)) 15:46:31 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:46:31 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 15:46:31 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 15:46:31 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 15:46:31 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 15:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:46:32 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)) 15:46:32 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)) 15:46:32 INFO - Installing collected packages: simplejson 15:46:32 INFO - Running setup.py install for simplejson 15:46:33 INFO - building 'simplejson._speedups' extension 15:46:33 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 15:46:33 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 15:46:33 INFO - Successfully installed simplejson 15:46:33 INFO - Cleaning up... 15:46:34 INFO - Return code: 0 15:46:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:46:34 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')]} 15:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:34 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')]} 15:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:34 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 0x19891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, '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': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', '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 15:46:34 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 15:46:34 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 15:46:34 INFO - Using env: {'DISPLAY': ':0', 15:46:34 INFO - 'HOME': '/home/cltbld', 15:46:34 INFO - 'LANG': 'en_US.UTF-8', 15:46:34 INFO - 'LANGUAGE': 'en_US:en', 15:46:34 INFO - 'LOGNAME': 'cltbld', 15:46:34 INFO - 'MAIL': '/var/mail/cltbld', 15:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:34 INFO - 'MOZ_NO_REMOTE': '1', 15:46:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:34 INFO - 'NO_EM_RESTART': '1', 15:46:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:34 INFO - 'PWD': '/builds/slave/test', 15:46:34 INFO - 'SHELL': '/bin/bash', 15:46:34 INFO - 'SHLVL': '1', 15:46:34 INFO - 'TERM': 'linux', 15:46:34 INFO - 'TMOUT': '86400', 15:46:34 INFO - 'USER': 'cltbld', 15:46:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:34 INFO - '_': '/tools/buildbot/bin/python'} 15:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:46:34 INFO - Cleaning up... 15:46:34 INFO - Return code: 0 15:46:34 INFO - Running post-action listener: _resource_record_post_action 15:46:34 INFO - Running post-action listener: _start_resource_monitoring 15:46:34 INFO - Starting resource monitoring. 15:46:34 INFO - [mozharness: 2017-08-25 22:46:34.274074Z] Finished create-virtualenv step (success) 15:46:34 INFO - [mozharness: 2017-08-25 22:46:34.274672Z] Running install step. 15:46:34 INFO - Running pre-action listener: _resource_record_pre_action 15:46:34 INFO - Running main action method: install 15:46:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:46:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:46:34 INFO - Reading from file tmpfile_stdout 15:46:34 INFO - Detecting whether we're running mozinstall >=1.0... 15:46:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:46:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:46:34 INFO - Reading from file tmpfile_stdout 15:46:34 INFO - Output received: 15:46:34 INFO - Usage: mozinstall [options] installer 15:46:34 INFO - Options: 15:46:34 INFO - -h, --help show this help message and exit 15:46:34 INFO - -d DEST, --destination=DEST 15:46:34 INFO - Directory to install application into. [default: 15:46:34 INFO - "/builds/slave/test"] 15:46:34 INFO - --app=APP Application being installed. [default: firefox] 15:46:34 INFO - mkdir: /builds/slave/test/build/application 15:46:34 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'] 15:46:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:46:52 INFO - Reading from file tmpfile_stdout 15:46:52 INFO - Output received: 15:46:52 INFO - /builds/slave/test/build/application/firefox/firefox 15:46:52 INFO - Running post-action listener: _resource_record_post_action 15:46:52 INFO - [mozharness: 2017-08-25 22:46:52.341812Z] Finished install step (success) 15:46:52 INFO - [mozharness: 2017-08-25 22:46:52.342093Z] Running setup-mitmproxy step. 15:46:52 INFO - Running pre-action listener: _resource_record_pre_action 15:46:52 INFO - Running main action method: setup_mitmproxy 15:46:52 INFO - Skipping: mitmproxy is not required 15:46:52 INFO - Running post-action listener: _resource_record_post_action 15:46:52 INFO - [mozharness: 2017-08-25 22:46:52.343215Z] Finished setup-mitmproxy step (success) 15:46:52 INFO - [mozharness: 2017-08-25 22:46:52.343569Z] Running run-tests step. 15:46:52 INFO - Running pre-action listener: _resource_record_pre_action 15:46:52 INFO - Running pre-action listener: _set_gcov_prefix 15:46:52 INFO - Running main action method: run_tests 15:46:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:46:52 INFO - Python 2.7.3 15:46:52 INFO - Return code: 0 15:46:52 INFO - grabbing minidump binary from tooltool 15:46:52 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')]} 15:46:52 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 0x1cf5130>, '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 0x1cf6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf64c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:46:52 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 15:46:52 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 15:46:52 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 15:46:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:46:52 INFO - Return code: 0 15:46:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:46:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:46:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:46:52 INFO - ENV: RUST_BACKTRACE is now full 15:46:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:46:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:46:52 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/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 15:46:52 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/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 15:46:52 INFO - Using env: {'DISPLAY': ':0', 15:46:52 INFO - 'HOME': '/home/cltbld', 15:46:52 INFO - 'LANG': 'en_US.UTF-8', 15:46:52 INFO - 'LANGUAGE': 'en_US:en', 15:46:52 INFO - 'LOGNAME': 'cltbld', 15:46:52 INFO - 'MAIL': '/var/mail/cltbld', 15:46:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:46:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:46:52 INFO - 'MOZ_ACCELERATED': '1', 15:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:52 INFO - 'MOZ_NO_REMOTE': '1', 15:46:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:46:52 INFO - 'MOZ_WEBRENDER': '1', 15:46:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:52 INFO - 'NO_EM_RESTART': '1', 15:46:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:52 INFO - 'PWD': '/builds/slave/test', 15:46:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:46:52 INFO - 'RUST_BACKTRACE': 'full', 15:46:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:46:52 INFO - 'SHELL': '/bin/bash', 15:46:52 INFO - 'SHLVL': '1', 15:46:52 INFO - 'TERM': 'linux', 15:46:52 INFO - 'TMOUT': '86400', 15:46:52 INFO - 'USER': 'cltbld', 15:46:52 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:52 INFO - '_': '/tools/buildbot/bin/python'} 15:46:52 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/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 15:46:52 INFO - mozversion application_buildid: 20170825220049 15:46:52 INFO - mozversion application_changeset: bb813dab9832c1b780293edbc5477f920bf0c88e 15:46:52 INFO - mozversion application_display_name: Nightly 15:46:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:46:52 INFO - mozversion application_name: Firefox 15:46:52 INFO - mozversion application_remotingname: firefox 15:46:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:46:52 INFO - mozversion application_vendor: Mozilla 15:46:52 INFO - mozversion application_version: 57.0a1 15:46:52 INFO - mozversion platform_buildid: 20170825220049 15:46:52 INFO - mozversion platform_changeset: bb813dab9832c1b780293edbc5477f920bf0c88e 15:46:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:46:52 INFO - mozversion platform_version: 57.0a1 15:46:52 INFO - using testdate: 1503701212 15:46:52 INFO - actual date: 1503701212 15:46:52 INFO - starting webserver on 'localhost:50498' 15:46:52 INFO - SUITE-START | Running 2 tests 15:46:52 INFO - TEST-START | dromaeo_css 15:46:52 INFO - Initialising browser for dromaeo_css test... 15:46:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50498/getInfo.html -profile /tmp/tmpLNJL_y/profile 15:46:52 INFO - TEST-INFO | started process 11177 (/builds/slave/test/build/application/firefox/firefox http://localhost:50498/getInfo.html) 15:46:58 INFO - TEST-INFO | 11177: exit 0 15:46:59 INFO - Browser initialized. 15:46:59 INFO - Running cycle 1/1 for dromaeo_css test... 15:46:59 INFO - Using env: {'DISPLAY': ':0', 15:46:59 INFO - 'HOME': '/home/cltbld', 15:46:59 INFO - 'JSGC_DISABLE_POISONING': '1', 15:46:59 INFO - 'LANG': 'en_US.UTF-8', 15:46:59 INFO - 'LANGUAGE': 'en_US:en', 15:46:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:46:59 INFO - 'LOGNAME': 'cltbld', 15:46:59 INFO - 'MAIL': '/var/mail/cltbld', 15:46:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:46:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:46:59 INFO - 'MOZ_ACCELERATED': '1', 15:46:59 INFO - 'MOZ_CRASHREPORTER': '1', 15:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:46:59 INFO - 'MOZ_NO_REMOTE': '1', 15:46:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:46:59 INFO - 'MOZ_WEBRENDER': '1', 15:46:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:59 INFO - 'NO_EM_RESTART': '1', 15:46:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:59 INFO - 'PWD': '/builds/slave/test', 15:46:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:46:59 INFO - 'RUST_BACKTRACE': 'full', 15:46:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:46:59 INFO - 'SHELL': '/bin/bash', 15:46:59 INFO - 'SHLVL': '1', 15:46:59 INFO - 'TERM': 'linux', 15:46:59 INFO - 'TMOUT': '86400', 15:46:59 INFO - 'USER': 'cltbld', 15:46:59 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 15:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:59 INFO - '_': '/tools/buildbot/bin/python'} 15:46:59 INFO - TEST-INFO | started process 11360 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLNJL_y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:46:59 INFO - PID 11360 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:47:00 INFO - PID 11360 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:47:00 INFO - PID 11360 | 15:47:00 INFO - PID 11360 | (/builds/slave/test/build/application/firefox/firefox:11421): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:47:00 INFO - PID 11360 | 15:47:01 INFO - PID 11360 | 15:47:01 INFO - PID 11360 | (/builds/slave/test/build/application/firefox/firefox:11479): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:47:01 INFO - PID 11360 | 15:47:01 INFO - PID 11360 | __metrics Screen width/height:1600/1200 15:47:01 INFO - PID 11360 | colorDepth:24 15:47:01 INFO - PID 11360 | Browser inner width/height: 1024/768 15:47:01 INFO - PID 11360 | __metrics 15:47:10 INFO - PID 11360 | RSS: Main: 209735680 15:47:10 INFO - PID 11360 | 15:47:14 INFO - PID 11360 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:50:10 INFO - PID 11360 | Cycle 1(1): loaded http://localhost:50498/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50498/tests/dromaeo/cssquery-ext.html) 15:50:11 INFO - PID 11360 | RSS: Main: 221388800 15:50:11 INFO - PID 11360 | 15:53:14 INFO - PID 11360 | Cycle 1(1): loaded http://localhost:50498/tests/dromaeo/cssquery-ext.html (next: http://localhost:50498/tests/dromaeo/cssquery-jquery.html) 15:53:14 INFO - PID 11360 | RSS: Main: 218415104 15:53:14 INFO - PID 11360 | 15:56:16 INFO - PID 11360 | Cycle 1(1): loaded http://localhost:50498/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50498/tests/dromaeo/cssquery-mootools.html) 15:56:17 INFO - PID 11360 | RSS: Main: 217772032 15:56:17 INFO - PID 11360 | 15:59:19 INFO - PID 11360 | Cycle 1(1): loaded http://localhost:50498/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50498/tests/dromaeo/cssquery-prototype.html) 15:59:20 INFO - PID 11360 | RSS: Main: 221114368 15:59:20 INFO - PID 11360 | 16:02:24 INFO - PID 11360 | Cycle 1(1): loaded http://localhost:50498/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50498/tests/dromaeo/cssquery-yui.html) 16:02:24 INFO - PID 11360 | RSS: Main: 222896128 16:02:24 INFO - PID 11360 | 16:05:26 INFO - PID 11360 | Cycle 1(1): loaded http://localhost:50498/tests/dromaeo/cssquery-yui.html (next: http://localhost:50498/tests/dromaeo/cssquery-dojo.html) 16:05:27 INFO - PID 11360 | RSS: Main: 223625216 16:05:27 INFO - PID 11360 | 16:05:27 INFO - PID 11360 | __start_tp_report 16:05:27 INFO - PID 11360 | _x_x_mozilla_page_load 16:05:27 INFO - PID 11360 | _x_x_mozilla_page_load_details 16:05:27 INFO - PID 11360 | |i|pagename|runs| 16:05:27 INFO - PID 11360 | |0;dojo.html;3283.0807373935277;3324.8670053197875;3325.235195904942;3339.348826978737;3379.2903490267045;6193.7832175873855;6239.095331176976;6266.624002559844;6279.843003924904;6283.214598175228;6204.410580994799;6215.968920155404;6314.621122732639;6359.1097246385425;6395.392437718421;5775;5825.8834823303505;5865.70671466426;5912;5921.319048309435;5672.602917795756;5733.569982251348;5845.152452894327;5858.88282234355;5875.206847075654;5710.657360558359;5763.279590051244;5803.2165657636315;5831.358550559414;5846.473817356458;5760.279965004374;5781.855453613652;5810.24466819315;5833.416658334175;5838.328592211908;5571.331440227199;5698.6580805151825;5817.621854579439;5835.124731290298;5837.3287071986415;6132.969335153296;6193.0710393440895;6253.468455181315;6268.373162683741;6314.336994615545;6773.796786096375;6781;6816.522843400964;6837.076994605714;6856.41720453762;6775.559588626724;6822.215445223813;6846.315368463163;6895.75864844733;6911.930880691179;6157.538184636171;6303.495720342407;6368;6389.904151437732;6413.807585772434;6796.116504854337;6913.377795998284;6962.094927659473;7087.89368159478;7119.288071192943;6419.807405777834;6604.603723776589;6739.393454589111;6762.830929226809;6766.49251306154;6607.834804129839;6656.334366563305;6674.098996635489;6704.195496540447;6710.127683401224;6608.2400523939405;6676.86646267072;6678;6680.131582894192;6688.565243259172;6689.264180940093;6695.330466953265;6696.631685257357;6715.294894036056;6717.059611654372;5206.6095042871175;5220.843374698765;5227.529522343008;5234.581233501311;5254.4220135785035;4625.95374046262;4672.953270467252;4713.505081966344;4763.404574428197;4822.132016237111;5852.385499522574;5855.912161317584;5921.259842519685;5947.762089516457;5950.702464876739;6647.302033286532;6668.299828518033;6681.799546013627;6779.0509328694025;6788.626625535643;5901.321348044944;5921.7631294747725;5932.228810254639;5933.110033495011;5940.762369505258;5828.067509198508;5862.14998825168;5872.794452194153;5872.823815285463;5921.082232254007;6604.1084453599115;6609.537332386687;6677.399034086956;6681.5656982296;6695.46436285096;6572.605643661395;6794.9320506796275;6800.285969973082;6882.587044777129;6885.621290829053;6530.412262896362;6570.145881035435;6632.568883022588;6641.667916604247;6674.16572928384;6637.767678131242;6673.866522669617;6698.765543205965;6724.428423583854;6732.360425759545;6460.45086167681;6792.184937807495;6810.148731408574;6878.449724022166;6926.688299026657;6528.771492997722;6556.147700799056;6640.335966403321;6649.335066493312;6657.001449782571;6238.157848690369;6348.873022539616;6379.106925030406;6530.608163510205;6629.237637671729;6363.872722545616;6382.744690212525;6431.099646049651;6431.356864313719;6463.418292353523;6423.164988551458;6475.546711730133;6493.318201588765;6519.4132528072705;6583.144191255297;6272.937270627235;6313.305536390902;6351.872962540816;6428.710708018057;6473.611583305017 16:05:27 INFO - PID 11360 | |1;ext.html;757.9317861392477;797.5892415406071;828.9834203315933;845.5434065604574;857.0101532729695;38665.80667096664;39113;39230.607693923055;39561.80219098904;39855.402168967456;35737.64262357376;36178.457323140174;36304.092397690074;36325.09187270313;36351.81824090876;6773.085633439497;6802.115724955749;6857.897131543031;6876.277990810968;6886.793396198097;6728.428083612899;6740.865182696343;6748.763793267237;6749.2913244109395;6803.047573339736;6758.9324106758795;6824.590524568517;7445.404367650575;7627.389808815282;7629.771106866803;7498.062742157231;7544.0569928758905;7567.054118235221;7583.696652133908;7596.354309883639;7373.115226172894;7532.661030253652;7533.623318834037;7539.0576177977755;7539.321461068475;35095;35962.820185898905;36190.276194476224;36347;36360.63639363599;3409.454487282023;3433.4334834752235;3437.8968630941113;3438.7592868499214;3455.3089382123676;29810.70189298101;29823.850880745675;29824.552631710543;29905.401891962258;29967.250818729488;5023.296738456619;5068.898622027576;5092.949070509285;5095.566876815476;5176.922346164811;216196.99999999843;216580;218629.90685046633;220429;220669;45107.77446112748;45144.3228351575;45163.548364516595;45285.41501047448;45306.77346613246;47036.88334024977;47276;50368;50705.492945070815;51289.743551282;15160.848391515943;15200.239988000556;15250.923745381202;15261.694766104838;15299.311530981133;12638.810417843739;12656.240625562496;12658.177218480769;12666.49334026647;12697.809532857014;13380.264085475197;13514.459421623222;13541.796873046913;13547.932260338832;13607.387667554984;5565.499105080562;5570.41510641385;5589.413111623302;5696.23100881384;5706.486416222477;953.4517652349926;958.1376760915232;961.7403301108661;969.3021024862088;969.3553786731885;20335.593288134154;20543.4864128398;20907.8954605226;20932.581348372947;20935.371938841556;1041.1358572384902;1048.4705223861902;1061.692109288313;1062.3519653011656;1080.8432777247256;11264;11268.267562608404;11279.718007049725;11423.200375973602;11452.797456267139;228264;228701;228880;229847;231068;41642.167156656695;48328.758356208695;48336.5166348332;48520;48529.514704852496;1301.1737546657941;1403.5648948825897;1429.5496918470649;1447.7032208397254;1453.14264583896;25994.220173394828;26071.352019579608;26219.21342359732;26320.605190922535;26622.334441639116;33484.83257583697;33726.156846079044;33754.66245337515;33807.4928876072;33845.83077084599;169583;169930;170318;170488;170553;32707.672923270464;32815.835920820246;32894.67105328916;32988.50517242195;33023;40785.59214407818;40823.79588102159;44089;44090.55909440993;44403.33394999142;31472.52791208088;32046.19884502906;32078.198045048128;32665;32724;31077.378452430374;31351.84324078365;31611.683883161793;31902.52146217762;32043;35366.64633353634;35666.64333356633;35921.28157436889;36072.27855442929;36336;30840.38319233558;31987.520187197675;32130.357392851543;32307.51538726968;32325.676743232267;23403.180888668816;23506.529869402173;23565.528689425773;23814.523709525365;24008.519829603658 16:05:27 INFO - PID 11360 | |2;jquery.html;557.3284192547981;559.6306437751085;560.3611882454003;560.6776103740349;570.284293212019;21465.248716294933;22070.88964555179;22124;22270.55458890823;22273.999999999978;22535;24787.628185577232;24790.132345367914;24803;24857.37856553587;6353.174087368647;6491.545591808575;6545.116409284757;6549.901751473708;6550.574212676184;5879.294484661847;6388.456981156607;6437.0988061671405;6443.710033048524;6475.384838440341;6315.084312774645;6319.74721011159;6323.336049714782;6376.7130479128555;6393.8401539961405;6333.240011198663;6377.7767778127545;6399.936000640004;6400.775972840975;6407.070974708665;5933.61431506955;5938.970305148447;5943.613665111797;5958.970205148947;5959.851003724898;27826.886924522994;28268;28632.856835715895;28821.279468013257;29298.560521592193;1983.6826107822678;2011.4971257185703;2015.3651599746033;2023.9595208095902;2025.989870050655;18933.715994260096;19376.32182873607;19471.221151153935;19545.706814397796;19584.31454899086;2308.8383813133087;2396.8681722505257;2442.1818690738623;2479.3677612208953;2482.6027835546406;132051;133232;133264;134713;135127.3243633795;14362.13827170373;14650.926745366205;14692.338844752012;14695.632609184642;15039.721623662112;22317.442063948205;22350.77649223487;22398.992045357998;22408.887955560444;22516.88741556282;9580.66467673628;10059.497025148714;10069.798604028025;10111.39331640104;10128.189744820396;9187.127222913812;9301.72094837156;9349.266670328694;9367.62529498826;9444.811103778024;7526.435517336222;8492.702755403654;8821.514816685143;8968.641254349754;8972.685955991508;2436.1108195508514;2512.6356678281545;2527.734587868284;2532.430203204301;2672.385351369196;905.7780843693206;910.6858133943779;927.8840144981878;942.0861764088822;963.9662611808553;18174.636507270046;18208;18672.7199092011;18861.05694715259;19025.42923712291;971.6890595009529;990.7077412163429;997.9950100249453;1000.9749756256006;1001.8647482589903;10369.63706270277;10390.116840068678;10526.789464210673;10746.677599672023;10795.946020270005;467687;476214;481926;483339;489896;104131;106564.46717766517;107889.4605526983;109436;109585;1110.9611163609236;1114.5151858941385;1127.9151520591176;1131.8737855833333;1136.2387200575633;16016.439424620083;16526.917365413577;16577.336906523604;16607.169641517634;16651.666966660356;49621.75189124031;52215.47784522258;52244.73877630587;53170.73414632902;53181.734091329294;64135;64256.67871660612;64582;65272;67262.66368668125;11897.643070707893;12483.12618116723;14172.433102675777;14196.651318124761;14327.856721433069;18006.189721462302;21720.782792172507;22282.777172228718;22767.203147889744;23260.883695581415;16998.66002679915;17548.38580649671;17698.203580839152;17755.46733597994;17799.822001779816;16115.919420402823;16390.344386224417;16441.588960276087;16582.585435364213;17354.132293385534;18189.18148683286;18469.81530184681;18573.90713046426;18734.625307494047;18803;14242.430302787772;14427.49503767363;14473.276336183359;14587.124772513684;14735.189564574051;16691.582710432336;16864.57838554046;16910.239039243017;17041.91479042597;17169.227384767964 16:05:27 INFO - PID 11360 | |3;mootools.html;1298.2210673595844;1332.340491456729;1350.2236214176849;1351.8783309502144;1364.2155760437745;13931.094478858868;14492.927535362309;14566.417343306255;14614.415423383065;14640.414383424664;15086.47197348093;15318.234088295596;15326.923365383158;15335.92332038341;15342.309596068177;2669.479451506957;2707.7698395636394;2723.400851812598;2739.4794988952035;2746.5056289867816;2350.083467447697;2631.74998375155;2633.723459036802;2704.4996675615075;2736.630554875086;2641.643378143945;2669.346010227497;2674.812763106583;2676.8661566921674;2681.6245725598433;2608.15235048609;2615.3069436599317;2618;2636.2354917074026;2644.127437945483;2563.2310306907852;2581.832180908235;2590.209985954283;2597.2597809624167;2613.202973093206;14143.575692729131;14498.130112193303;14557.199354035523;14562;14574.41702331916;7673.808154796175;7872.212778722082;7931.167227441093;7938.841223175504;7943.96028019862;5244.580433565306;5292.311999440087;5405.108157154064;5448.45515448456;5545.639533430314;1858.609691964689;1884.6324966631512;1889.5937373464633;1908.4465505003532;1933.2750218668;25084.372890677696;25182.370440738945;25359.492810143693;25414.237572872844;25414.36464088394;6268.310485846553;6375.808725738235;6411.358864113645;6513.1532900722605;6648.135742353463;15941.44204952821;16238.918805406134;16301.755473667905;16685.749713754303;16856.15719214059;7531.096268447822;7744.148143704188;7752.496087754278;7774.844503109906;7782.299593036654;6881.587104773729;7162.140543134858;7167.856642867114;7169.749058782918;7238;4983.9252411213865;5053.898922021539;5087.592992560586;5090.745462726849;5163.173892177234;1433.168762117969;1438.7769895666188;1449.4419648435428;1457.5335892514506;1469.6472846516758;863.6329559937002;868.5570359116896;895.0154829687292;902.2871931174299;903.1645727701849;7481.438892083115;7498.512596681198;8933.731988040368;9122.407043542282;9147.542622868963;967.6032826541084;989.5052473763119;991.4844280973854;998.1814911772423;1002.7593377589326;4909.729964851896;5199.038177937094;5224.477552244745;5412.702301373462;5434.70109143993;19944;20514.794852051586;20609;20766.1693532257;20845.791542084688;19120.808791911903;20150.09324580368;20335.084921178583;20343.18627254923;20415.387538373874;10514.842277365693;10612.310199836986;10629.46852657364;10643.521041553304;10687.770906345715;8652.610632521677;8820.823583528421;8872.645094196418;8891.822163556562;8941.508217048126;17823.46529604114;18124.45626631203;18177.545561361072;18178.181981810587;18658.160382782535;17858.285668573113;17897.910510447364;18201.27194912189;18237;18366;11606.88393116058;11701.707457313294;11750.23623464472;11832.645020649394;11850.644480665595;13296.534621288209;13435.932820336227;13447.529336473177;13452.730945381234;13474.730505389642;11147.498362573866;11257.21199516026;11307.547698091985;11396.658100257006;11521.53913843437;10493.47532623381;10585.629502967358;10636.627718029831;10645.733856653645;10900;9448.385854919408;9657.806843862943;9722.562484688064;9735.26985476078;9749.610015599581;7277.672504737194;7294.817629559304;7318.670659820427;7410.481266311306;7416.369608583331;7338.669759860926;7419;7470.962645186739;7498.587577683062;7596.012518372576 16:05:27 INFO - PID 11360 | |4;prototype.html;481.7543053042947;489;504.44762984531934;516.6435159739781;527.6464768605034;6298.307186209519;6336.619802811829;6364.522660800435;6377.61734295942;6378.394052564999;5992.88014239716;6209.106222602105;6223.284322302925;6271.498280137589;6326.335734747854;5057.115004874142;5057.772400241998;5063.392392912856;5066.18940969445;5101.7194054327;5027.572656324217;5029.195328747401;5051.924221136685;5052.747362631872;5105.693658380509;4815.205491093965;4931.939632978909;4972.726500042496;4977.278294647274;5000.274960130779;4956.628252881031;4998;5006.69959802413;5019.749012549376;5027.4721154279005;4462.709923854972;4475.889256966409;4493.842715504974;4496.842610508649;4519.954800452019;6212.161358216673;6262.3111457739615;6266.308096813835;6276.215473065867;6291.528135389818;5773.480386765169;5774.422557744234;5811.331696854873;5815.651060936358;5818.941810581882;6612.636305003223;6636.303188165221;6697.832554186136;6721.1598550181225;6726.260111387744;4912.901741965176;4914.140025495524;4915.21356582945;4919.680220785637;4927.310176575283;6977.930220697779;6978.825529361705;7020.859582808417;7043.154821421411;7047.400970917478;5045.520675535819;5079.187330027178;5094.490550944876;5097.1844504879045;5111.830664987731;5020.723860187652;5118.155504341742;5141.434442211354;5156.871078223075;5176.559992400688;5784.161296611967;5796.478316951495;5801.50687191585;5804.912926306109;5818.854528636733;6683.799486015428;6720.1599800025;6726.697298621574;6756.729730810713;6775.762848300285;1671.866250699941;1760.9823901761074;1780.4480611010629;1782.7949785774802;1796.97304540432;1144.862616486027;1172.2263306218026;1193.886580774825;1206.945687444067;1231.150506150753;5880.26496687914;5886.735096920649;5903.76384944606;5953;5957.851053723692;6697.9665101675155;6711.362420570038;6755.932440675629;6763.376789570468;6892.931070689229;5862.560307976919;5887.381824908409;5892;5892.1734262378795;5900.144479050514;3559.288142371536;3567.4827150063097;3582.4089025311055;3585.6414358564457;3599.190182208982;6552.213734351813;6613.239477460057;6635.336466353423;6685.732570697215;6695.966520167368;7079.82300442493;7090.539114957512;7102.5028248022145;7106.395956343666;7112.857742845114;6592.8351791205605;6650.767223147167;6693.364130407604;6698.8995165072565;6709;6726;6760.966195168992;6773.593584384953;6778.93221067783;6808.693608787517;6753.425958793361;6753.763618273337;6788.287229840796;6844.93155068443;6883.208431030495;6635.47384101669;6653;6693.330666933268;6699.698513566804;6751.257361690112;4987.276844857548;5024.371953505812;5027.974860125676;5053.317802096817;5064.696118232918;6803.537239493533;6839.350261725128;6848.452123829982;6903.551269167488;6903.861922761617;6493.772717954849;6574.934250657432;6581.835454113685;6633.303503132294;6696.899546506904;6629;6638.834029149117;6724.865502690017;6752.745770164961;6755.392014718699;6024.698765061817;6291.433770960635;6466;6522;6622;6600.877850765478;6636.303188165173;6679.899801502884;6682.766103186365;6721;6267.655278959701;6391.840203994937;6452.257990330984;6471.967640161769;6500.219973603198 16:05:27 INFO - PID 11360 | |5;yui.html;861.4493910960274;881.1056777370968;883.138939533954;887.7203680840535;893.9910600893991;16732.749008764862;20558.972051397428;22588.548229035452;22603.321900343017;22684.206052788155;20183.29358472454;20298.18807247708;20376.184952601914;20423.795762042413;21219.68170477444;949.5015117063546;963.1235575626182;968.6222373274429;969.5588507229194;971.771633666088;694.3230350408362;717.6842189436658;952.7141857442774;955.9952200238989;959.5538074795254;788.8106854354942;963.5326866469757;1010.0303708439907;1014.90865822076;1015.2943704125636;999.7403271877487;1003.2325271167526;1005.2008653120787;1010.4190090697841;1013.17932474695;735.69100977589;736.6537727268176;737.0308044920912;738.8559230949967;741.7700512840983;16504.587385315343;17748.112594370228;18850.71723924142;18900.905495472573;19074.80925190758;19972.201111955503;19973.30093446737;20337.18651253963;20410.69383959242;20451.89774051135;15130.319135638923;16408.425705100384;16416.671666566603;16430.260638271306;16482.917585411997;3622.492851000862;3674.8530058797614;3684.9447258291148;3685.668289853926;3686.0232038509816;122467;122865.38567307107;125891;127328;127521;15455.149966751711;15580.143092130042;15604.921975390278;15633.530994070195;15737;22746.545069098527;28201.153965381072;29245.268868278465;29433.264168395533;29543.488803451517;7509;7644.579548124907;8648.697295594624;8705.651773928972;8731.126887311219;4212.768297743653;4298.216240260257;4303.978480107579;4306.20335237982;4323.69734118615;4923.679960802537;5075.5178258065425;5096.796128154832;5106.744662766847;5138.2292656101135;1021.8313978193663;1057.6879820452984;1061.543536279392;1065.2915810985608;1072.3512275073624;663.8506336074345;668.1581207678361;677.8847595908708;682.7951614515625;684.1584850633749;10695.251332406813;10861.193724997953;10892.183086268562;11071.557137714562;11720.824187637192;678.1760161403918;680.7106979533777;680.8263892707427;694.5207806613421;697.979060628182;5400.459954004568;5474.370447398521;5573.7213139343685;5601;5667.489925906688;108287.45856270826;109347.45326273477;109471.45264273787;109996;111345;94909;95674.52162739141;96227;98596;99806;86184.13815861761;86685.56657216673;90426.54786726156;90473;91757.54121229217;9883.505824708593;9961.053699898497;9967.60129594837;10037.899621003697;10090.293679442368;40976.1804763909;41760.58239417567;41767;41803.7909810449;43042.78478607587;54202.45797541974;55104.72447637736;55200.72399637976;55407;55934;14666.486672966395;14847.554573362817;14947.850521494645;15056.397744090551;15324;18260;18672.719909201645;18719.438416847515;18749.71875421897;18807.153678084247;15860.44488442899;15967.840321596635;16054.117023563816;16067.27697253603;16436.589085272964;17133.742993854852;17332.133393330536;17512.211950462006;17535;17836.91081544584;16065.19674016318;16248;16307.1031093291;16361.263743131825;16451.341946322013;12600.369981501071;12675.30285834288;12704.936475317565;12723.55467558631;12970.22178669283;12865.292408917225;12921.28932908699;12994.480220791062;13058.21650700961;13095.476180952655 16:05:27 INFO - PID 11360 | __end_tp_report 16:05:27 INFO - PID 11360 | __start_cc_report 16:05:27 INFO - PID 11360 | _x_x_mozilla_cycle_collect,368 16:05:27 INFO - PID 11360 | __end_cc_report 16:05:27 INFO - PID 11360 | __startTimestamp1503702327128__endTimestamp 16:05:27 INFO - PID 11360 | 16:05:27 INFO - PID 11360 | ------- Summary: start ------- 16:05:27 INFO - PID 11360 | Number of tests: 6 16:05:27 INFO - PID 11360 | 16:05:27 INFO - PID 11360 | [#0] dojo.html Cycles:165 Average:6211.42 Median:6413.81 stddev:717.21 (11.2%) stddev-sans-first:681.63 16:05:27 INFO - PID 11360 | Values: 3283.1 3324.9 3325.2 3339.3 3379.3 6193.8 6239.1 6266.6 6279.8 6283.2 6204.4 6216.0 6314.6 6359.1 6395.4 5775.0 5825.9 5865.7 5912.0 5921.3 5672.6 5733.6 5845.2 5858.9 5875.2 5710.7 5763.3 5803.2 5831.4 5846.5 5760.3 5781.9 5810.2 5833.4 5838.3 5571.3 5698.7 5817.6 5835.1 5837.3 6133.0 6193.1 6253.5 6268.4 6314.3 6773.8 6781.0 6816.5 6837.1 6856.4 6775.6 6822.2 6846.3 6895.8 6911.9 6157.5 6303.5 6368.0 6389.9 6413.8 6796.1 6913.4 6962.1 7087.9 7119.3 6419.8 6604.6 6739.4 6762.8 6766.5 6607.8 6656.3 6674.1 6704.2 6710.1 6608.2 6676.9 6678.0 6680.1 6688.6 6689.3 6695.3 6696.6 6715.3 6717.1 5206.6 5220.8 5227.5 5234.6 5254.4 4626.0 4673.0 4713.5 4763.4 4822.1 5852.4 5855.9 5921.3 5947.8 5950.7 6647.3 6668.3 6681.8 6779.1 6788.6 5901.3 5921.8 5932.2 5933.1 5940.8 5828.1 5862.1 5872.8 5872.8 5921.1 6604.1 6609.5 6677.4 6681.6 6695.5 6572.6 6794.9 6800.3 6882.6 6885.6 6530.4 6570.1 6632.6 6641.7 6674.2 6637.8 6673.9 6698.8 6724.4 6732.4 6460.5 6792.2 6810.1 6878.4 6926.7 6528.8 6556.1 6640.3 6649.3 6657.0 6238.2 6348.9 6379.1 6530.6 6629.2 6363.9 6382.7 6431.1 6431.4 6463.4 6423.2 6475.5 6493.3 6519.4 6583.1 6272.9 6313.3 6351.9 6428.7 6473.6 16:05:27 INFO - PID 11360 | 16:05:27 INFO - PID 11360 | [#1] ext.html Cycles:180 Average:36658.09 Median:3421.44 stddev:53843.44 (1573.7%) stddev-sans-first:53927.00 16:05:27 INFO - PID 11360 | Values: 757.9 797.6 829.0 845.5 857.0 38665.8 39113.0 39230.6 39561.8 39855.4 35737.6 36178.5 36304.1 36325.1 36351.8 6773.1 6802.1 6857.9 6876.3 6886.8 6728.4 6740.9 6748.8 6749.3 6803.0 6758.9 6824.6 7445.4 7627.4 7629.8 7498.1 7544.1 7567.1 7583.7 7596.4 7373.1 7532.7 7533.6 7539.1 7539.3 35095.0 35962.8 36190.3 36347.0 36360.6 3409.5 3433.4 3437.9 3438.8 3455.3 29810.7 29823.9 29824.6 29905.4 29967.3 5023.3 5068.9 5092.9 5095.6 5176.9 216197.0 216580.0 218629.9 220429.0 220669.0 45107.8 45144.3 45163.5 45285.4 45306.8 47036.9 47276.0 50368.0 50705.5 51289.7 15160.8 15200.2 15250.9 15261.7 15299.3 12638.8 12656.2 12658.2 12666.5 12697.8 13380.3 13514.5 13541.8 13547.9 13607.4 5565.5 5570.4 5589.4 5696.2 5706.5 953.5 958.1 961.7 969.3 969.4 20335.6 20543.5 20907.9 20932.6 20935.4 1041.1 1048.5 1061.7 1062.4 1080.8 11264.0 11268.3 11279.7 11423.2 11452.8 228264.0 228701.0 228880.0 229847.0 231068.0 41642.2 48328.8 48336.5 48520.0 48529.5 1301.2 1403.6 1429.5 1447.7 1453.1 25994.2 26071.4 26219.2 26320.6 26622.3 33484.8 33726.2 33754.7 33807.5 33845.8 169583.0 169930.0 170318.0 170488.0 170553.0 32707.7 32815.8 32894.7 32988.5 33023.0 40785.6 40823.8 44089.0 44090.6 44403.3 31472.5 32046.2 32078.2 32665.0 32724.0 31077.4 31351.8 31611.7 31902.5 32043.0 35366.6 35666.6 35921.3 36072.3 36336.0 30840.4 31987.5 32130.4 32307.5 32325.7 23403.2 23506.5 23565.5 23814.5 24008.5 16:05:27 INFO - PID 11360 | 16:05:27 INFO - PID 11360 | [#2] jquery.html Cycles:180 Average:33454.48 Median:22374.88 stddev:80613.52 (360.3%) stddev-sans-first:80801.82 16:05:27 INFO - PID 11360 | Values: 557.3 559.6 560.4 560.7 570.3 21465.2 22070.9 22124.0 22270.6 22274.0 22535.0 24787.6 24790.1 24803.0 24857.4 6353.2 6491.5 6545.1 6549.9 6550.6 5879.3 6388.5 6437.1 6443.7 6475.4 6315.1 6319.7 6323.3 6376.7 6393.8 6333.2 6377.8 6399.9 6400.8 6407.1 5933.6 5939.0 5943.6 5959.0 5959.9 27826.9 28268.0 28632.9 28821.3 29298.6 1983.7 2011.5 2015.4 2024.0 2026.0 18933.7 19376.3 19471.2 19545.7 19584.3 2308.8 2396.9 2442.2 2479.4 2482.6 132051.0 133232.0 133264.0 134713.0 135127.3 14362.1 14650.9 14692.3 14695.6 15039.7 22317.4 22350.8 22399.0 22408.9 22516.9 9580.7 10059.5 10069.8 10111.4 10128.2 9187.1 9301.7 9349.3 9367.6 9444.8 7526.4 8492.7 8821.5 8968.6 8972.7 2436.1 2512.6 2527.7 2532.4 2672.4 905.8 910.7 927.9 942.1 964.0 18174.6 18208.0 18672.7 18861.1 19025.4 971.7 990.7 998.0 1001.0 1001.9 10369.6 10390.1 10526.8 10746.7 10795.9 467687.0 476214.0 481926.0 483339.0 489896.0 104131.0 106564.5 107889.5 109436.0 109585.0 1111.0 1114.5 1127.9 1131.9 1136.2 16016.4 16526.9 16577.3 16607.2 16651.7 49621.8 52215.5 52244.7 53170.7 53181.7 64135.0 64256.7 64582.0 65272.0 67262.7 11897.6 12483.1 14172.4 14196.7 14327.9 18006.2 21720.8 22282.8 22767.2 23260.9 16998.7 17548.4 17698.2 17755.5 17799.8 16115.9 16390.3 16441.6 16582.6 17354.1 18189.2 18469.8 18573.9 18734.6 18803.0 14242.4 14427.5 14473.3 14587.1 14735.2 16691.6 16864.6 16910.2 17041.9 17169.2 16:05:27 INFO - PID 11360 | 16:05:27 INFO - PID 11360 | [#3] mootools.html Cycles:180 Average:9081.76 Median:2602.71 stddev:6345.18 (243.8%) stddev-sans-first:6336.03 16:05:27 INFO - PID 11360 | Values: 1298.2 1332.3 1350.2 1351.9 1364.2 13931.1 14492.9 14566.4 14614.4 14640.4 15086.5 15318.2 15326.9 15335.9 15342.3 2669.5 2707.8 2723.4 2739.5 2746.5 2350.1 2631.7 2633.7 2704.5 2736.6 2641.6 2669.3 2674.8 2676.9 2681.6 2608.2 2615.3 2618.0 2636.2 2644.1 2563.2 2581.8 2590.2 2597.3 2613.2 14143.6 14498.1 14557.2 14562.0 14574.4 7673.8 7872.2 7931.2 7938.8 7944.0 5244.6 5292.3 5405.1 5448.5 5545.6 1858.6 1884.6 1889.6 1908.4 1933.3 25084.4 25182.4 25359.5 25414.2 25414.4 6268.3 6375.8 6411.4 6513.2 6648.1 15941.4 16238.9 16301.8 16685.7 16856.2 7531.1 7744.1 7752.5 7774.8 7782.3 6881.6 7162.1 7167.9 7169.7 7238.0 4983.9 5053.9 5087.6 5090.7 5163.2 1433.2 1438.8 1449.4 1457.5 1469.6 863.6 868.6 895.0 902.3 903.2 7481.4 7498.5 8933.7 9122.4 9147.5 967.6 989.5 991.5 998.2 1002.8 4909.7 5199.0 5224.5 5412.7 5434.7 19944.0 20514.8 20609.0 20766.2 20845.8 19120.8 20150.1 20335.1 20343.2 20415.4 10514.8 10612.3 10629.5 10643.5 10687.8 8652.6 8820.8 8872.6 8891.8 8941.5 17823.5 18124.5 18177.5 18178.2 18658.2 17858.3 17897.9 18201.3 18237.0 18366.0 11606.9 11701.7 11750.2 11832.6 11850.6 13296.5 13435.9 13447.5 13452.7 13474.7 11147.5 11257.2 11307.5 11396.7 11521.5 10493.5 10585.6 10636.6 10645.7 10900.0 9448.4 9657.8 9722.6 9735.3 9749.6 7277.7 7294.8 7318.7 7410.5 7416.4 7338.7 7419.0 7471.0 7498.6 7596.0 16:05:27 INFO - PID 11360 | 16:05:27 INFO - PID 11360 | [#4] prototype.html Cycles:180 Average:5595.54 Median:6264.31 stddev:1588.70 (25.4%) stddev-sans-first:1546.09 16:05:27 INFO - PID 11360 | Values: 481.8 489.0 504.4 516.6 527.6 6298.3 6336.6 6364.5 6377.6 6378.4 5992.9 6209.1 6223.3 6271.5 6326.3 5057.1 5057.8 5063.4 5066.2 5101.7 5027.6 5029.2 5051.9 5052.7 5105.7 4815.2 4931.9 4972.7 4977.3 5000.3 4956.6 4998.0 5006.7 5019.7 5027.5 4462.7 4475.9 4493.8 4496.8 4520.0 6212.2 6262.3 6266.3 6276.2 6291.5 5773.5 5774.4 5811.3 5815.7 5818.9 6612.6 6636.3 6697.8 6721.2 6726.3 4912.9 4914.1 4915.2 4919.7 4927.3 6977.9 6978.8 7020.9 7043.2 7047.4 5045.5 5079.2 5094.5 5097.2 5111.8 5020.7 5118.2 5141.4 5156.9 5176.6 5784.2 5796.5 5801.5 5804.9 5818.9 6683.8 6720.2 6726.7 6756.7 6775.8 1671.9 1761.0 1780.4 1782.8 1797.0 1144.9 1172.2 1193.9 1206.9 1231.2 5880.3 5886.7 5903.8 5953.0 5957.9 6698.0 6711.4 6755.9 6763.4 6892.9 5862.6 5887.4 5892.0 5892.2 5900.1 3559.3 3567.5 3582.4 3585.6 3599.2 6552.2 6613.2 6635.3 6685.7 6696.0 7079.8 7090.5 7102.5 7106.4 7112.9 6592.8 6650.8 6693.4 6698.9 6709.0 6726.0 6761.0 6773.6 6778.9 6808.7 6753.4 6753.8 6788.3 6844.9 6883.2 6635.5 6653.0 6693.3 6699.7 6751.3 4987.3 5024.4 5028.0 5053.3 5064.7 6803.5 6839.4 6848.5 6903.6 6903.9 6493.8 6574.9 6581.8 6633.3 6696.9 6629.0 6638.8 6724.9 6752.7 6755.4 6024.7 6291.4 6466.0 6522.0 6622.0 6600.9 6636.3 6679.9 6682.8 6721.0 6267.7 6391.8 6452.3 6472.0 6500.2 16:05:27 INFO - PID 11360 | 16:05:27 INFO - PID 11360 | [#5] yui.html Cycles:180 Average:22847.89 Median:21904.11 stddev:31755.05 (145.0%) stddev-sans-first:31801.22 16:05:27 INFO - PID 11360 | Values: 861.4 881.1 883.1 887.7 894.0 16732.7 20559.0 22588.5 22603.3 22684.2 20183.3 20298.2 20376.2 20423.8 21219.7 949.5 963.1 968.6 969.6 971.8 694.3 717.7 952.7 956.0 959.6 788.8 963.5 1010.0 1014.9 1015.3 999.7 1003.2 1005.2 1010.4 1013.2 735.7 736.7 737.0 738.9 741.8 16504.6 17748.1 18850.7 18900.9 19074.8 19972.2 19973.3 20337.2 20410.7 20451.9 15130.3 16408.4 16416.7 16430.3 16482.9 3622.5 3674.9 3684.9 3685.7 3686.0 122467.0 122865.4 125891.0 127328.0 127521.0 15455.1 15580.1 15604.9 15633.5 15737.0 22746.5 28201.2 29245.3 29433.3 29543.5 7509.0 7644.6 8648.7 8705.7 8731.1 4212.8 4298.2 4304.0 4306.2 4323.7 4923.7 5075.5 5096.8 5106.7 5138.2 1021.8 1057.7 1061.5 1065.3 1072.4 663.9 668.2 677.9 682.8 684.2 10695.3 10861.2 10892.2 11071.6 11720.8 678.2 680.7 680.8 694.5 698.0 5400.5 5474.4 5573.7 5601.0 5667.5 108287.5 109347.5 109471.5 109996.0 111345.0 94909.0 95674.5 96227.0 98596.0 99806.0 86184.1 86685.6 90426.5 90473.0 91757.5 9883.5 9961.1 9967.6 10037.9 10090.3 40976.2 41760.6 41767.0 41803.8 43042.8 54202.5 55104.7 55200.7 55407.0 55934.0 14666.5 14847.6 14947.9 15056.4 15324.0 18260.0 18672.7 18719.4 18749.7 18807.2 15860.4 15967.8 16054.1 16067.3 16436.6 17133.7 17332.1 17512.2 17535.0 17836.9 16065.2 16248.0 16307.1 16361.3 16451.3 12600.4 12675.3 12704.9 12723.6 12970.2 12865.3 12921.3 12994.5 13058.2 13095.5 16:05:27 INFO - PID 11360 | -------- Summary: end -------- 16:05:27 INFO - PID 11360 | 16:05:28 INFO - TEST-INFO | 11360: exit 0 16:05:28 INFO - TEST-OK | dromaeo_css | took 1115506ms 16:05:28 INFO - TEST-START | kraken 16:05:28 INFO - Initialising browser for kraken test... 16:05:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50498/getInfo.html -profile /tmp/tmpfvc3k8/profile 16:05:28 INFO - TEST-INFO | started process 11793 (/builds/slave/test/build/application/firefox/firefox http://localhost:50498/getInfo.html) 16:05:34 INFO - TEST-INFO | 11793: exit 0 16:05:34 INFO - Browser initialized. 16:05:34 INFO - Running cycle 1/1 for kraken test... 16:05:34 INFO - Using env: {'DISPLAY': ':0', 16:05:34 INFO - 'HOME': '/home/cltbld', 16:05:34 INFO - 'JSGC_DISABLE_POISONING': '1', 16:05:34 INFO - 'LANG': 'en_US.UTF-8', 16:05:34 INFO - 'LANGUAGE': 'en_US:en', 16:05:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:05:34 INFO - 'LOGNAME': 'cltbld', 16:05:34 INFO - 'MAIL': '/var/mail/cltbld', 16:05:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:05:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:05:34 INFO - 'MOZ_ACCELERATED': '1', 16:05:34 INFO - 'MOZ_CRASHREPORTER': '1', 16:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:05:34 INFO - 'MOZ_NO_REMOTE': '1', 16:05:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:05:34 INFO - 'MOZ_WEBRENDER': '1', 16:05:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:34 INFO - 'NO_EM_RESTART': '1', 16:05:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:34 INFO - 'PWD': '/builds/slave/test', 16:05:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:05:34 INFO - 'RUST_BACKTRACE': 'full', 16:05:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:05:34 INFO - 'SHELL': '/bin/bash', 16:05:34 INFO - 'SHLVL': '1', 16:05:34 INFO - 'TERM': 'linux', 16:05:34 INFO - 'TMOUT': '86400', 16:05:34 INFO - 'USER': 'cltbld', 16:05:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687', 16:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:34 INFO - '_': '/tools/buildbot/bin/python'} 16:05:34 INFO - TEST-INFO | started process 11975 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfvc3k8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:05:35 INFO - PID 11975 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:05:35 INFO - PID 11975 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:05:35 INFO - PID 11975 | 16:05:35 INFO - PID 11975 | (/builds/slave/test/build/application/firefox/firefox:12034): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:05:35 INFO - PID 11975 | 16:05:36 INFO - PID 11975 | 16:05:36 INFO - PID 11975 | (/builds/slave/test/build/application/firefox/firefox:12091): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:05:36 INFO - PID 11975 | 16:05:36 INFO - PID 11975 | __metrics Screen width/height:1600/1200 16:05:36 INFO - PID 11975 | colorDepth:24 16:05:36 INFO - PID 11975 | Browser inner width/height: 1024/768 16:05:36 INFO - PID 11975 | __metrics 16:05:46 INFO - PID 11975 | RSS: Main: 210653184 16:05:46 INFO - PID 11975 | 16:05:48 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50498/tests/kraken/driver.html?testName=audio-beat-detection) 16:05:49 INFO - PID 11975 | RSS: Main: 215941120 16:05:49 INFO - PID 11975 | 16:05:52 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50498/tests/kraken/driver.html?testName=audio-dft) 16:05:53 INFO - PID 11975 | RSS: Main: 216670208 16:05:53 INFO - PID 11975 | 16:05:56 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50498/tests/kraken/driver.html?testName=audio-fft) 16:05:56 INFO - PID 11975 | RSS: Main: 210395136 16:05:56 INFO - PID 11975 | 16:05:58 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50498/tests/kraken/driver.html?testName=audio-oscillator) 16:05:59 INFO - PID 11975 | RSS: Main: 211324928 16:05:59 INFO - PID 11975 | 16:06:01 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50498/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:06:02 INFO - PID 11975 | RSS: Main: 211415040 16:06:02 INFO - PID 11975 | 16:06:05 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50498/tests/kraken/driver.html?testName=imaging-darkroom) 16:06:06 INFO - PID 11975 | RSS: Main: 212987904 16:06:06 INFO - PID 11975 | 16:06:09 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50498/tests/kraken/driver.html?testName=imaging-desaturate) 16:06:10 INFO - PID 11975 | RSS: Main: 211652608 16:06:10 INFO - PID 11975 | 16:06:13 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50498/tests/kraken/driver.html?testName=json-parse-financial) 16:06:13 INFO - PID 11975 | RSS: Main: 212631552 16:06:13 INFO - PID 11975 | 16:06:15 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50498/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:06:16 INFO - PID 11975 | RSS: Main: 213241856 16:06:16 INFO - PID 11975 | 16:06:18 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50498/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:06:18 INFO - PID 11975 | RSS: Main: 213012480 16:06:18 INFO - PID 11975 | 16:06:20 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50498/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:06:21 INFO - PID 11975 | RSS: Main: 213516288 16:06:21 INFO - PID 11975 | 16:06:24 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50498/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:06:24 INFO - PID 11975 | RSS: Main: 213536768 16:06:24 INFO - PID 11975 | 16:06:27 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50498/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:06:28 INFO - PID 11975 | RSS: Main: 213544960 16:06:28 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | Cycle 1(1): loaded http://localhost:50498/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50498/tests/kraken/driver.html?testName=ai-astar) 16:06:30 INFO - PID 11975 | RSS: Main: 214847488 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | __start_tp_report 16:06:30 INFO - PID 11975 | _x_x_mozilla_page_load 16:06:30 INFO - PID 11975 | _x_x_mozilla_page_load_details 16:06:30 INFO - PID 11975 | |i|pagename|runs| 16:06:30 INFO - PID 11975 | |0;ai-astar;99;110;113;116;109;111;108;100;109;117 16:06:30 INFO - PID 11975 | |1;audio-beat-detection;152;123;157;167;121;145;145;123;180;120 16:06:30 INFO - PID 11975 | |2;audio-dft;164;165;164;163;164;164;177;177;162;175 16:06:30 INFO - PID 11975 | |3;audio-fft;74;81;80;169;79;77;84;75;177;77 16:06:30 INFO - PID 11975 | |4;audio-oscillator;82;83;137;81;90;79;129;81;79;125 16:06:30 INFO - PID 11975 | |5;imaging-gaussian-blur;121;118;115;115;116;122;125;121;115;123 16:06:30 INFO - PID 11975 | |6;imaging-darkroom;148;153;159;156;151;150;154;148;149;148 16:06:30 INFO - PID 11975 | |7;imaging-desaturate;108;101;96;102;95;96;97;94;97;94 16:06:30 INFO - PID 11975 | |8;json-parse-financial;52;52;59;52;53;53;52;51;52;52 16:06:30 INFO - PID 11975 | |9;json-stringify-tinderbox;60;57;57;56;56;62;57;56;57;67 16:06:30 INFO - PID 11975 | |10;stanford-crypto-aes;78;79;79;78;76;75;86;75;81;82 16:06:30 INFO - PID 11975 | |11;stanford-crypto-ccm;122;126;134;120;133;130;119;129;125;156 16:06:30 INFO - PID 11975 | |12;stanford-crypto-pbkdf2;180;173;176;175;173;176;180;172;171;174 16:06:30 INFO - PID 11975 | |13;stanford-crypto-sha256-iterative;68;70;69;64;64;64;66;74;64;66 16:06:30 INFO - PID 11975 | __end_tp_report 16:06:30 INFO - PID 11975 | __start_cc_report 16:06:30 INFO - PID 11975 | _x_x_mozilla_cycle_collect,937 16:06:30 INFO - PID 11975 | __end_cc_report 16:06:30 INFO - PID 11975 | __startTimestamp1503702390565__endTimestamp 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | ------- Summary: start ------- 16:06:30 INFO - PID 11975 | Number of tests: 14 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#0] ai-astar Cycles:10 Average:109.20 Median:112.00 stddev:5.92 (5.3%) stddev-sans-first:5.00 16:06:30 INFO - PID 11975 | Values: 99.0 110.0 113.0 116.0 109.0 111.0 108.0 100.0 109.0 117.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:148.50 stddev:21.19 (14.3%) stddev-sans-first:22.24 16:06:30 INFO - PID 11975 | Values: 152.0 123.0 157.0 167.0 121.0 145.0 145.0 123.0 180.0 120.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#2] audio-dft Cycles:10 Average:167.50 Median:164.50 stddev:6.17 (3.8%) stddev-sans-first:6.41 16:06:30 INFO - PID 11975 | Values: 164.0 165.0 164.0 163.0 164.0 164.0 177.0 177.0 162.0 175.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#3] audio-fft Cycles:10 Average:97.30 Median:78.00 stddev:40.05 (51.3%) stddev-sans-first:41.58 16:06:30 INFO - PID 11975 | Values: 74.0 81.0 80.0 169.0 79.0 77.0 84.0 75.0 177.0 77.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:81.00 stddev:23.66 (29.2%) stddev-sans-first:24.49 16:06:30 INFO - PID 11975 | Values: 82.0 83.0 137.0 81.0 90.0 79.0 129.0 81.0 79.0 125.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#5] imaging-gaussian-blur Cycles:10 Average:119.10 Median:121.00 stddev:3.75 (3.1%) stddev-sans-first:3.92 16:06:30 INFO - PID 11975 | Values: 121.0 118.0 115.0 115.0 116.0 122.0 125.0 121.0 115.0 123.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#6] imaging-darkroom Cycles:10 Average:151.60 Median:152.00 stddev:3.81 (2.5%) stddev-sans-first:3.81 16:06:30 INFO - PID 11975 | Values: 148.0 153.0 159.0 156.0 151.0 150.0 154.0 148.0 149.0 148.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:95.50 stddev:4.42 (4.6%) stddev-sans-first:2.85 16:06:30 INFO - PID 11975 | Values: 108.0 101.0 96.0 102.0 95.0 96.0 97.0 94.0 97.0 94.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:52.00 stddev:2.25 (4.3%) stddev-sans-first:2.37 16:06:30 INFO - PID 11975 | Values: 52.0 52.0 59.0 52.0 53.0 53.0 52.0 51.0 52.0 52.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:57.00 stddev:3.57 (6.3%) stddev-sans-first:3.74 16:06:30 INFO - PID 11975 | Values: 60.0 57.0 57.0 56.0 56.0 62.0 57.0 56.0 57.0 67.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:79.00 stddev:3.41 (4.3%) stddev-sans-first:3.61 16:06:30 INFO - PID 11975 | Values: 78.0 79.0 79.0 78.0 76.0 75.0 86.0 75.0 81.0 82.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#11] stanford-crypto-ccm Cycles:10 Average:129.40 Median:129.50 stddev:10.67 (8.2%) stddev-sans-first:10.97 16:06:30 INFO - PID 11975 | Values: 122.0 126.0 134.0 120.0 133.0 130.0 119.0 129.0 125.0 156.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:175.50 stddev:3.09 (1.8%) stddev-sans-first:2.70 16:06:30 INFO - PID 11975 | Values: 180.0 173.0 176.0 175.0 173.0 176.0 180.0 172.0 171.0 174.0 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:3.35 (5.0%) stddev-sans-first:3.53 16:06:30 INFO - PID 11975 | Values: 68.0 70.0 69.0 64.0 64.0 64.0 66.0 74.0 64.0 66.0 16:06:30 INFO - PID 11975 | -------- Summary: end -------- 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | 16:06:30 INFO - PID 11975 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 16:06:30 INFO - PID 11975 | 16:06:31 INFO - PID 11975 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:06:31 INFO - TEST-INFO | 11975: exit 0 16:06:31 INFO - TEST-OK | kraken | took 63381ms 16:06:31 INFO - SUITE-END | took 1178s 16:06:31 INFO - Completed test suite (00:19:39) 16:06:31 INFO - javascript benchmark 16:06:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8358.105720393889, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3283.0807373935277, 3324.8670053197875, 3325.235195904942, 3339.348826978737, 3379.2903490267045, 6193.7832175873855, 6239.095331176976, 6266.624002559844, 6279.843003924904, 6283.214598175228, 6204.410580994799, 6215.968920155404, 6314.621122732639, 6359.1097246385425, 6395.392437718421, 5775.0, 5825.8834823303505, 5865.70671466426, 5912.0, 5921.319048309435, 5672.602917795756, 5733.569982251348, 5845.152452894327, 5858.88282234355, 5875.206847075654, 5710.657360558359, 5763.279590051244, 5803.2165657636315, 5831.358550559414, 5846.473817356458, 5760.279965004374, 5781.855453613652, 5810.24466819315, 5833.416658334175, 5838.328592211908, 5571.331440227199, 5698.6580805151825, 5817.621854579439, 5835.124731290298, 5837.3287071986415, 6132.969335153296, 6193.0710393440895, 6253.468455181315, 6268.373162683741, 6314.336994615545, 6773.796786096375, 6781.0, 6816.522843400964, 6837.076994605714, 6856.41720453762, 6775.559588626724, 6822.215445223813, 6846.315368463163, 6895.75864844733, 6911.930880691179, 6157.538184636171, 6303.495720342407, 6368.0, 6389.904151437732, 6413.807585772434, 6796.116504854337, 6913.377795998284, 6962.094927659473, 7087.89368159478, 7119.288071192943, 6419.807405777834, 6604.603723776589, 6739.393454589111, 6762.830929226809, 6766.49251306154, 6607.834804129839, 6656.334366563305, 6674.098996635489, 6704.195496540447, 6710.127683401224, 6608.2400523939405, 6676.86646267072, 6678.0, 6680.131582894192, 6688.565243259172, 6689.264180940093, 6695.330466953265, 6696.631685257357, 6715.294894036056, 6717.059611654372, 5206.6095042871175, 5220.843374698765, 5227.529522343008, 5234.581233501311, 5254.4220135785035, 4625.95374046262, 4672.953270467252, 4713.505081966344, 4763.404574428197, 4822.132016237111, 5852.385499522574, 5855.912161317584, 5921.259842519685, 5947.762089516457, 5950.702464876739, 6647.302033286532, 6668.299828518033, 6681.799546013627, 6779.0509328694025, 6788.626625535643, 5901.321348044944, 5921.7631294747725, 5932.228810254639, 5933.110033495011, 5940.762369505258, 5828.067509198508, 5862.14998825168, 5872.794452194153, 5872.823815285463, 5921.082232254007, 6604.1084453599115, 6609.537332386687, 6677.399034086956, 6681.5656982296, 6695.46436285096, 6572.605643661395, 6794.9320506796275, 6800.285969973082, 6882.587044777129, 6885.621290829053, 6530.412262896362, 6570.145881035435, 6632.568883022588, 6641.667916604247, 6674.16572928384, 6637.767678131242, 6673.866522669617, 6698.765543205965, 6724.428423583854, 6732.360425759545, 6460.45086167681, 6792.184937807495, 6810.148731408574, 6878.449724022166, 6926.688299026657, 6528.771492997722, 6556.147700799056, 6640.335966403321, 6649.335066493312, 6657.001449782571, 6238.157848690369, 6348.873022539616, 6379.106925030406, 6530.608163510205, 6629.237637671729, 6363.872722545616, 6382.744690212525, 6431.099646049651, 6431.356864313719, 6463.418292353523, 6423.164988551458, 6475.546711730133, 6493.318201588765, 6519.4132528072705, 6583.144191255297, 6272.937270627235, 6313.305536390902, 6351.872962540816, 6428.710708018057, 6473.611583305017], "value": 6160.198826143839, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.9317861392477, 797.5892415406071, 828.9834203315933, 845.5434065604574, 857.0101532729695, 38665.80667096664, 39113.0, 39230.607693923055, 39561.80219098904, 39855.402168967456, 35737.64262357376, 36178.457323140174, 36304.092397690074, 36325.09187270313, 36351.81824090876, 6773.085633439497, 6802.115724955749, 6857.897131543031, 6876.277990810968, 6886.793396198097, 6728.428083612899, 6740.865182696343, 6748.763793267237, 6749.2913244109395, 6803.047573339736, 6758.9324106758795, 6824.590524568517, 7445.404367650575, 7627.389808815282, 7629.771106866803, 7498.062742157231, 7544.0569928758905, 7567.054118235221, 7583.696652133908, 7596.354309883639, 7373.115226172894, 7532.661030253652, 7533.623318834037, 7539.0576177977755, 7539.321461068475, 35095.0, 35962.820185898905, 36190.276194476224, 36347.0, 36360.63639363599, 3409.454487282023, 3433.4334834752235, 3437.8968630941113, 3438.7592868499214, 3455.3089382123676, 29810.70189298101, 29823.850880745675, 29824.552631710543, 29905.401891962258, 29967.250818729488, 5023.296738456619, 5068.898622027576, 5092.949070509285, 5095.566876815476, 5176.922346164811, 216196.99999999843, 216580.0, 218629.90685046633, 220429.0, 220669.0, 45107.77446112748, 45144.3228351575, 45163.548364516595, 45285.41501047448, 45306.77346613246, 47036.88334024977, 47276.0, 50368.0, 50705.492945070815, 51289.743551282, 15160.848391515943, 15200.239988000556, 15250.923745381202, 15261.694766104838, 15299.311530981133, 12638.810417843739, 12656.240625562496, 12658.177218480769, 12666.49334026647, 12697.809532857014, 13380.264085475197, 13514.459421623222, 13541.796873046913, 13547.932260338832, 13607.387667554984, 5565.499105080562, 5570.41510641385, 5589.413111623302, 5696.23100881384, 5706.486416222477, 953.4517652349926, 958.1376760915232, 961.7403301108661, 969.3021024862088, 969.3553786731885, 20335.593288134154, 20543.4864128398, 20907.8954605226, 20932.581348372947, 20935.371938841556, 1041.1358572384902, 1048.4705223861902, 1061.692109288313, 1062.3519653011656, 1080.8432777247256, 11264.0, 11268.267562608404, 11279.718007049725, 11423.200375973602, 11452.797456267139, 228264.0, 228701.0, 228880.0, 229847.0, 231068.0, 41642.167156656695, 48328.758356208695, 48336.5166348332, 48520.0, 48529.514704852496, 1301.1737546657941, 1403.5648948825897, 1429.5496918470649, 1447.7032208397254, 1453.14264583896, 25994.220173394828, 26071.352019579608, 26219.21342359732, 26320.605190922535, 26622.334441639116, 33484.83257583697, 33726.156846079044, 33754.66245337515, 33807.4928876072, 33845.83077084599, 169583.0, 169930.0, 170318.0, 170488.0, 170553.0, 32707.672923270464, 32815.835920820246, 32894.67105328916, 32988.50517242195, 33023.0, 40785.59214407818, 40823.79588102159, 44089.0, 44090.55909440993, 44403.33394999142, 31472.52791208088, 32046.19884502906, 32078.198045048128, 32665.0, 32724.0, 31077.378452430374, 31351.84324078365, 31611.683883161793, 31902.52146217762, 32043.0, 35366.64633353634, 35666.64333356633, 35921.28157436889, 36072.27855442929, 36336.0, 30840.38319233558, 31987.520187197675, 32130.357392851543, 32307.51538726968, 32325.676743232267, 23403.180888668816, 23506.529869402173, 23565.528689425773, 23814.523709525365, 24008.519829603658], "value": 16471.252949500842, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.3284192547981, 559.6306437751085, 560.3611882454003, 560.6776103740349, 570.284293212019, 21465.248716294933, 22070.88964555179, 22124.0, 22270.55458890823, 22273.999999999978, 22535.0, 24787.628185577232, 24790.132345367914, 24803.0, 24857.37856553587, 6353.174087368647, 6491.545591808575, 6545.116409284757, 6549.901751473708, 6550.574212676184, 5879.294484661847, 6388.456981156607, 6437.0988061671405, 6443.710033048524, 6475.384838440341, 6315.084312774645, 6319.74721011159, 6323.336049714782, 6376.7130479128555, 6393.8401539961405, 6333.240011198663, 6377.7767778127545, 6399.936000640004, 6400.775972840975, 6407.070974708665, 5933.61431506955, 5938.970305148447, 5943.613665111797, 5958.970205148947, 5959.851003724898, 27826.886924522994, 28268.0, 28632.856835715895, 28821.279468013257, 29298.560521592193, 1983.6826107822678, 2011.4971257185703, 2015.3651599746033, 2023.9595208095902, 2025.989870050655, 18933.715994260096, 19376.32182873607, 19471.221151153935, 19545.706814397796, 19584.31454899086, 2308.8383813133087, 2396.8681722505257, 2442.1818690738623, 2479.3677612208953, 2482.6027835546406, 132051.0, 133232.0, 133264.0, 134713.0, 135127.3243633795, 14362.13827170373, 14650.926745366205, 14692.338844752012, 14695.632609184642, 15039.721623662112, 22317.442063948205, 22350.77649223487, 22398.992045357998, 22408.887955560444, 22516.88741556282, 9580.66467673628, 10059.497025148714, 10069.798604028025, 10111.39331640104, 10128.189744820396, 9187.127222913812, 9301.72094837156, 9349.266670328694, 9367.62529498826, 9444.811103778024, 7526.435517336222, 8492.702755403654, 8821.514816685143, 8968.641254349754, 8972.685955991508, 2436.1108195508514, 2512.6356678281545, 2527.734587868284, 2532.430203204301, 2672.385351369196, 905.7780843693206, 910.6858133943779, 927.8840144981878, 942.0861764088822, 963.9662611808553, 18174.636507270046, 18208.0, 18672.7199092011, 18861.05694715259, 19025.42923712291, 971.6890595009529, 990.7077412163429, 997.9950100249453, 1000.9749756256006, 1001.8647482589903, 10369.63706270277, 10390.116840068678, 10526.789464210673, 10746.677599672023, 10795.946020270005, 467687.0, 476214.0, 481926.0, 483339.0, 489896.0, 104131.0, 106564.46717766517, 107889.4605526983, 109436.0, 109585.0, 1110.9611163609236, 1114.5151858941385, 1127.9151520591176, 1131.8737855833333, 1136.2387200575633, 16016.439424620083, 16526.917365413577, 16577.336906523604, 16607.169641517634, 16651.666966660356, 49621.75189124031, 52215.47784522258, 52244.73877630587, 53170.73414632902, 53181.734091329294, 64135.0, 64256.67871660612, 64582.0, 65272.0, 67262.66368668125, 11897.643070707893, 12483.12618116723, 14172.433102675777, 14196.651318124761, 14327.856721433069, 18006.189721462302, 21720.782792172507, 22282.777172228718, 22767.203147889744, 23260.883695581415, 16998.66002679915, 17548.38580649671, 17698.203580839152, 17755.46733597994, 17799.822001779816, 16115.919420402823, 16390.344386224417, 16441.588960276087, 16582.585435364213, 17354.132293385534, 18189.18148683286, 18469.81530184681, 18573.90713046426, 18734.625307494047, 18803.0, 14242.430302787772, 14427.49503767363, 14473.276336183359, 14587.124772513684, 14735.189564574051, 16691.582710432336, 16864.57838554046, 16910.239039243017, 17041.91479042597, 17169.227384767964], "value": 11519.17868254338, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.2210673595844, 1332.340491456729, 1350.2236214176849, 1351.8783309502144, 1364.2155760437745, 13931.094478858868, 14492.927535362309, 14566.417343306255, 14614.415423383065, 14640.414383424664, 15086.47197348093, 15318.234088295596, 15326.923365383158, 15335.92332038341, 15342.309596068177, 2669.479451506957, 2707.7698395636394, 2723.400851812598, 2739.4794988952035, 2746.5056289867816, 2350.083467447697, 2631.74998375155, 2633.723459036802, 2704.4996675615075, 2736.630554875086, 2641.643378143945, 2669.346010227497, 2674.812763106583, 2676.8661566921674, 2681.6245725598433, 2608.15235048609, 2615.3069436599317, 2618.0, 2636.2354917074026, 2644.127437945483, 2563.2310306907852, 2581.832180908235, 2590.209985954283, 2597.2597809624167, 2613.202973093206, 14143.575692729131, 14498.130112193303, 14557.199354035523, 14562.0, 14574.41702331916, 7673.808154796175, 7872.212778722082, 7931.167227441093, 7938.841223175504, 7943.96028019862, 5244.580433565306, 5292.311999440087, 5405.108157154064, 5448.45515448456, 5545.639533430314, 1858.609691964689, 1884.6324966631512, 1889.5937373464633, 1908.4465505003532, 1933.2750218668, 25084.372890677696, 25182.370440738945, 25359.492810143693, 25414.237572872844, 25414.36464088394, 6268.310485846553, 6375.808725738235, 6411.358864113645, 6513.1532900722605, 6648.135742353463, 15941.44204952821, 16238.918805406134, 16301.755473667905, 16685.749713754303, 16856.15719214059, 7531.096268447822, 7744.148143704188, 7752.496087754278, 7774.844503109906, 7782.299593036654, 6881.587104773729, 7162.140543134858, 7167.856642867114, 7169.749058782918, 7238.0, 4983.9252411213865, 5053.898922021539, 5087.592992560586, 5090.745462726849, 5163.173892177234, 1433.168762117969, 1438.7769895666188, 1449.4419648435428, 1457.5335892514506, 1469.6472846516758, 863.6329559937002, 868.5570359116896, 895.0154829687292, 902.2871931174299, 903.1645727701849, 7481.438892083115, 7498.512596681198, 8933.731988040368, 9122.407043542282, 9147.542622868963, 967.6032826541084, 989.5052473763119, 991.4844280973854, 998.1814911772423, 1002.7593377589326, 4909.729964851896, 5199.038177937094, 5224.477552244745, 5412.702301373462, 5434.70109143993, 19944.0, 20514.794852051586, 20609.0, 20766.1693532257, 20845.791542084688, 19120.808791911903, 20150.09324580368, 20335.084921178583, 20343.18627254923, 20415.387538373874, 10514.842277365693, 10612.310199836986, 10629.46852657364, 10643.521041553304, 10687.770906345715, 8652.610632521677, 8820.823583528421, 8872.645094196418, 8891.822163556562, 8941.508217048126, 17823.46529604114, 18124.45626631203, 18177.545561361072, 18178.181981810587, 18658.160382782535, 17858.285668573113, 17897.910510447364, 18201.27194912189, 18237.0, 18366.0, 11606.88393116058, 11701.707457313294, 11750.23623464472, 11832.645020649394, 11850.644480665595, 13296.534621288209, 13435.932820336227, 13447.529336473177, 13452.730945381234, 13474.730505389642, 11147.498362573866, 11257.21199516026, 11307.547698091985, 11396.658100257006, 11521.53913843437, 10493.47532623381, 10585.629502967358, 10636.627718029831, 10645.733856653645, 10900.0, 9448.385854919408, 9657.806843862943, 9722.562484688064, 9735.26985476078, 9749.610015599581, 7277.672504737194, 7294.817629559304, 7318.670659820427, 7410.481266311306, 7416.369608583331, 7338.669759860926, 7419.0, 7470.962645186739, 7498.587577683062, 7596.012518372576], "value": 6547.319558033236, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.7543053042947, 489.0, 504.44762984531934, 516.6435159739781, 527.6464768605034, 6298.307186209519, 6336.619802811829, 6364.522660800435, 6377.61734295942, 6378.394052564999, 5992.88014239716, 6209.106222602105, 6223.284322302925, 6271.498280137589, 6326.335734747854, 5057.115004874142, 5057.772400241998, 5063.392392912856, 5066.18940969445, 5101.7194054327, 5027.572656324217, 5029.195328747401, 5051.924221136685, 5052.747362631872, 5105.693658380509, 4815.205491093965, 4931.939632978909, 4972.726500042496, 4977.278294647274, 5000.274960130779, 4956.628252881031, 4998.0, 5006.69959802413, 5019.749012549376, 5027.4721154279005, 4462.709923854972, 4475.889256966409, 4493.842715504974, 4496.842610508649, 4519.954800452019, 6212.161358216673, 6262.3111457739615, 6266.308096813835, 6276.215473065867, 6291.528135389818, 5773.480386765169, 5774.422557744234, 5811.331696854873, 5815.651060936358, 5818.941810581882, 6612.636305003223, 6636.303188165221, 6697.832554186136, 6721.1598550181225, 6726.260111387744, 4912.901741965176, 4914.140025495524, 4915.21356582945, 4919.680220785637, 4927.310176575283, 6977.930220697779, 6978.825529361705, 7020.859582808417, 7043.154821421411, 7047.400970917478, 5045.520675535819, 5079.187330027178, 5094.490550944876, 5097.1844504879045, 5111.830664987731, 5020.723860187652, 5118.155504341742, 5141.434442211354, 5156.871078223075, 5176.559992400688, 5784.161296611967, 5796.478316951495, 5801.50687191585, 5804.912926306109, 5818.854528636733, 6683.799486015428, 6720.1599800025, 6726.697298621574, 6756.729730810713, 6775.762848300285, 1671.866250699941, 1760.9823901761074, 1780.4480611010629, 1782.7949785774802, 1796.97304540432, 1144.862616486027, 1172.2263306218026, 1193.886580774825, 1206.945687444067, 1231.150506150753, 5880.26496687914, 5886.735096920649, 5903.76384944606, 5953.0, 5957.851053723692, 6697.9665101675155, 6711.362420570038, 6755.932440675629, 6763.376789570468, 6892.931070689229, 5862.560307976919, 5887.381824908409, 5892.0, 5892.1734262378795, 5900.144479050514, 3559.288142371536, 3567.4827150063097, 3582.4089025311055, 3585.6414358564457, 3599.190182208982, 6552.213734351813, 6613.239477460057, 6635.336466353423, 6685.732570697215, 6695.966520167368, 7079.82300442493, 7090.539114957512, 7102.5028248022145, 7106.395956343666, 7112.857742845114, 6592.8351791205605, 6650.767223147167, 6693.364130407604, 6698.8995165072565, 6709.0, 6726.0, 6760.966195168992, 6773.593584384953, 6778.93221067783, 6808.693608787517, 6753.425958793361, 6753.763618273337, 6788.287229840796, 6844.93155068443, 6883.208431030495, 6635.47384101669, 6653.0, 6693.330666933268, 6699.698513566804, 6751.257361690112, 4987.276844857548, 5024.371953505812, 5027.974860125676, 5053.317802096817, 5064.696118232918, 6803.537239493533, 6839.350261725128, 6848.452123829982, 6903.551269167488, 6903.861922761617, 6493.772717954849, 6574.934250657432, 6581.835454113685, 6633.303503132294, 6696.899546506904, 6629.0, 6638.834029149117, 6724.865502690017, 6752.745770164961, 6755.392014718699, 6024.698765061817, 6291.433770960635, 6466.0, 6522.0, 6622.0, 6600.877850765478, 6636.303188165173, 6679.899801502884, 6682.766103186365, 6721.0, 6267.655278959701, 6391.840203994937, 6452.257990330984, 6471.967640161769, 6500.219973603198], "value": 5120.171152553618, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [861.4493910960274, 881.1056777370968, 883.138939533954, 887.7203680840535, 893.9910600893991, 16732.749008764862, 20558.972051397428, 22588.548229035452, 22603.321900343017, 22684.206052788155, 20183.29358472454, 20298.18807247708, 20376.184952601914, 20423.795762042413, 21219.68170477444, 949.5015117063546, 963.1235575626182, 968.6222373274429, 969.5588507229194, 971.771633666088, 694.3230350408362, 717.6842189436658, 952.7141857442774, 955.9952200238989, 959.5538074795254, 788.8106854354942, 963.5326866469757, 1010.0303708439907, 1014.90865822076, 1015.2943704125636, 999.7403271877487, 1003.2325271167526, 1005.2008653120787, 1010.4190090697841, 1013.17932474695, 735.69100977589, 736.6537727268176, 737.0308044920912, 738.8559230949967, 741.7700512840983, 16504.587385315343, 17748.112594370228, 18850.71723924142, 18900.905495472573, 19074.80925190758, 19972.201111955503, 19973.30093446737, 20337.18651253963, 20410.69383959242, 20451.89774051135, 15130.319135638923, 16408.425705100384, 16416.671666566603, 16430.260638271306, 16482.917585411997, 3622.492851000862, 3674.8530058797614, 3684.9447258291148, 3685.668289853926, 3686.0232038509816, 122467.0, 122865.38567307107, 125891.0, 127328.0, 127521.0, 15455.149966751711, 15580.143092130042, 15604.921975390278, 15633.530994070195, 15737.0, 22746.545069098527, 28201.153965381072, 29245.268868278465, 29433.264168395533, 29543.488803451517, 7509.0, 7644.579548124907, 8648.697295594624, 8705.651773928972, 8731.126887311219, 4212.768297743653, 4298.216240260257, 4303.978480107579, 4306.20335237982, 4323.69734118615, 4923.679960802537, 5075.5178258065425, 5096.796128154832, 5106.744662766847, 5138.2292656101135, 1021.8313978193663, 1057.6879820452984, 1061.543536279392, 1065.2915810985608, 1072.3512275073624, 663.8506336074345, 668.1581207678361, 677.8847595908708, 682.7951614515625, 684.1584850633749, 10695.251332406813, 10861.193724997953, 10892.183086268562, 11071.557137714562, 11720.824187637192, 678.1760161403918, 680.7106979533777, 680.8263892707427, 694.5207806613421, 697.979060628182, 5400.459954004568, 5474.370447398521, 5573.7213139343685, 5601.0, 5667.489925906688, 108287.45856270826, 109347.45326273477, 109471.45264273787, 109996.0, 111345.0, 94909.0, 95674.52162739141, 96227.0, 98596.0, 99806.0, 86184.13815861761, 86685.56657216673, 90426.54786726156, 90473.0, 91757.54121229217, 9883.505824708593, 9961.053699898497, 9967.60129594837, 10037.899621003697, 10090.293679442368, 40976.1804763909, 41760.58239417567, 41767.0, 41803.7909810449, 43042.78478607587, 54202.45797541974, 55104.72447637736, 55200.72399637976, 55407.0, 55934.0, 14666.486672966395, 14847.554573362817, 14947.850521494645, 15056.397744090551, 15324.0, 18260.0, 18672.719909201645, 18719.438416847515, 18749.71875421897, 18807.153678084247, 15860.44488442899, 15967.840321596635, 16054.117023563816, 16067.27697253603, 16436.589085272964, 17133.742993854852, 17332.133393330536, 17512.211950462006, 17535.0, 17836.91081544584, 16065.19674016318, 16248.0, 16307.1031093291, 16361.263743131825, 16451.341946322013, 12600.369981501071, 12675.30285834288, 12704.936475317565, 12723.55467558631, 12970.22178669283, 12865.292408917225, 12921.28932908699, 12994.480220791062, 13058.21650700961, 13095.476180952655], "value": 8700.263295326968, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1544.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 110.0, 113.0, 116.0, 109.0, 111.0, 108.0, 100.0, 109.0, 117.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 157.0, 167.0, 121.0, 145.0, 145.0, 123.0, 180.0, 120.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 164.0, 163.0, 164.0, 164.0, 177.0, 177.0, 162.0, 175.0], "value": 167.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 81.0, 80.0, 169.0, 79.0, 77.0, 84.0, 75.0, 177.0, 77.0], "value": 97.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 137.0, 81.0, 90.0, 79.0, 129.0, 81.0, 79.0, 125.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 118.0, 115.0, 115.0, 116.0, 122.0, 125.0, 121.0, 115.0, 123.0], "value": 119.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 153.0, 159.0, 156.0, 151.0, 150.0, 154.0, 148.0, 149.0, 148.0], "value": 151.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 101.0, 96.0, 102.0, 95.0, 96.0, 97.0, 94.0, 97.0, 94.0], "value": 98.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 59.0, 52.0, 53.0, 53.0, 52.0, 51.0, 52.0, 52.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 57.0, 56.0, 56.0, 62.0, 57.0, 56.0, 57.0, 67.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 79.0, 78.0, 76.0, 75.0, 86.0, 75.0, 81.0, 82.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 126.0, 134.0, 120.0, 133.0, 130.0, 119.0, 129.0, 125.0, 156.0], "value": 129.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 173.0, 176.0, 175.0, 173.0, 176.0, 180.0, 172.0, 171.0, 174.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 64.0, 64.0, 64.0, 66.0, 74.0, 64.0, 66.0], "value": 66.9, "unit": "score"}]}]} 16:06:31 INFO - Return code: 0 16:06:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:06:31 INFO - # TBPL SUCCESS # 16:06:31 INFO - Running post-action listener: _package_coverage_data 16:06:31 INFO - Running post-action listener: _resource_record_post_action 16:06:31 INFO - [mozharness: 2017-08-25 23:06:31.843742Z] Finished run-tests step (success) 16:06:31 INFO - Running post-run listener: _resource_record_post_run 16:06:31 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 426962944; Read time: 4; Write time: 310496 16:06:31 INFO - TinderboxPrint: CPU usage
13.3% 16:06:31 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 16:06:31 INFO - TinderboxPrint: I/O write bytes / time
426,962,944 / 310,496 16:06:31 INFO - TinderboxPrint: CPU idle
8,273.5 (86.7%) 16:06:31 INFO - TinderboxPrint: CPU user
1,206.1 (12.6%) 16:06:31 INFO - TinderboxPrint: Swap in / out
0 / 0 16:06:31 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107225088; Read time: 0; Write time: 71484 16:06:31 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:06:31 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 319131648; Read time: 0; Write time: 239012 16:06:32 INFO - Running post-run listener: _upload_blobber_files 16:06:32 INFO - Blob upload gear active. 16:06:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:06:32 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 16:06:32 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'] 16:06:32 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 16:06:32 INFO - (blobuploader) - INFO - Open directory for files ... 16:06:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:06:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:06:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:33 INFO - (blobuploader) - INFO - Done attempting. 16:06:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:06:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:06:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:41 INFO - (blobuploader) - INFO - Done attempting. 16:06:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:06:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:06:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:42 INFO - (blobuploader) - INFO - Done attempting. 16:06:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:06:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:06:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:43 INFO - (blobuploader) - INFO - Done attempting. 16:06:43 INFO - (blobuploader) - INFO - Iteration through files over. 16:06:43 INFO - Return code: 0 16:06:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:06:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:06:43 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/002d555930f48aa3c6b92944266d0548022324fef6b3cc8614f8e1f5c89a7eb0581b9406ca4435df782881258decb4df0897ef7465ed7882803b58cd159915fc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feccb39f27d83acd5fee4c68aa5af368fe9379f610a5b6bf3fc3cdd59050da2da9a4308a88f2d6914771da9776ef18dabf1fd412dc41a7bf26bd2ef0065daa6b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21e866df9c2f25be8eb6c775f78946f273d3decfa720325a75ec6f76b2faf39afcc2e4d01be2a2c2fba0c09e0d2e7af33114e74cd57a6c7d5f6caac7bbe205ea", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d138adaf52eb295fc072ad4d7475ef7c0949772bfaf22cb363295aafe1d9fa2e3ca608f79c4058406f3c22cfc2fcb21b9794450b62411adc3259e6dc726da9ad"} 16:06:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:06:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:06:43 INFO - Contents: 16:06:43 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/002d555930f48aa3c6b92944266d0548022324fef6b3cc8614f8e1f5c89a7eb0581b9406ca4435df782881258decb4df0897ef7465ed7882803b58cd159915fc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feccb39f27d83acd5fee4c68aa5af368fe9379f610a5b6bf3fc3cdd59050da2da9a4308a88f2d6914771da9776ef18dabf1fd412dc41a7bf26bd2ef0065daa6b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21e866df9c2f25be8eb6c775f78946f273d3decfa720325a75ec6f76b2faf39afcc2e4d01be2a2c2fba0c09e0d2e7af33114e74cd57a6c7d5f6caac7bbe205ea", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d138adaf52eb295fc072ad4d7475ef7c0949772bfaf22cb363295aafe1d9fa2e3ca608f79c4058406f3c22cfc2fcb21b9794450b62411adc3259e6dc726da9ad"} 16:06:43 INFO - Running post-run listener: copy_logs_to_upload_dir 16:06:43 INFO - Copying logs to upload dir... 16:06:43 INFO - mkdir: /builds/slave/test/build/upload/logs 16:06:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.999321 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-08-25 16:06:43.114929) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 16:06:43.117974) ========= 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=0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687 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/002d555930f48aa3c6b92944266d0548022324fef6b3cc8614f8e1f5c89a7eb0581b9406ca4435df782881258decb4df0897ef7465ed7882803b58cd159915fc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feccb39f27d83acd5fee4c68aa5af368fe9379f610a5b6bf3fc3cdd59050da2da9a4308a88f2d6914771da9776ef18dabf1fd412dc41a7bf26bd2ef0065daa6b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21e866df9c2f25be8eb6c775f78946f273d3decfa720325a75ec6f76b2faf39afcc2e4d01be2a2c2fba0c09e0d2e7af33114e74cd57a6c7d5f6caac7bbe205ea", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d138adaf52eb295fc072ad4d7475ef7c0949772bfaf22cb363295aafe1d9fa2e3ca608f79c4058406f3c22cfc2fcb21b9794450b62411adc3259e6dc726da9ad"} build_url:https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011452 build_url: 'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/002d555930f48aa3c6b92944266d0548022324fef6b3cc8614f8e1f5c89a7eb0581b9406ca4435df782881258decb4df0897ef7465ed7882803b58cd159915fc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feccb39f27d83acd5fee4c68aa5af368fe9379f610a5b6bf3fc3cdd59050da2da9a4308a88f2d6914771da9776ef18dabf1fd412dc41a7bf26bd2ef0065daa6b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21e866df9c2f25be8eb6c775f78946f273d3decfa720325a75ec6f76b2faf39afcc2e4d01be2a2c2fba0c09e0d2e7af33114e74cd57a6c7d5f6caac7bbe205ea", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d138adaf52eb295fc072ad4d7475ef7c0949772bfaf22cb363295aafe1d9fa2e3ca608f79c4058406f3c22cfc2fcb21b9794450b62411adc3259e6dc726da9ad"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 16:06:43.144211) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 16:06:43.144493) ========= 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=0e2a256762fed810e2f0f810000001b3-1503701139.661464-1275107687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004403 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 16:06:43.226385) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 16:06:43.226710) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 16:06:43.226989) ========= ========= Total master_lag: 0.19 =========