builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-042 starttime: 1506003148.37 results: success (0) revision: f7e9777221a34f9f23c2e4933307eb38b621b679 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:28.374793) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:28.375243) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:28.375561) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:28.419150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:28.419523) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 07:12:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.8M=0s 2017-09-21 07:12:29 (41.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.245691 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:28.705269) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:28.705837) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013903 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:28.769060) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 14 secs) (at 2017-09-21 07:12:28.769529) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f7e9777221a34f9f23c2e4933307eb38b621b679 --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 f7e9777221a34f9f23c2e4933307eb38b621b679 --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=74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 07:12:29,518 truncating revision to first 12 chars 2017-09-21 07:12:29,518 Setting DEBUG logging. 2017-09-21 07:12:29,518 attempt 1/10 2017-09-21 07:12:29,518 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f7e9777221a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 07:12:30,683 attempt 1/10 2017-09-21 07:12:31,193 current task status: no status available at this point. state: PENDING 2017-09-21 07:12:31,194 sleeping for 9.00s (attempt 1/10) 2017-09-21 07:12:40,203 attempt 2/10 2017-09-21 07:12:40,730 current task status: no status available at this point. state: PENDING 2017-09-21 07:12:40,730 sleeping for 12.50s (attempt 2/10) 2017-09-21 07:12:53,243 attempt 3/10 2017-09-21 07:12:53,785 current task status: no status available at this point. state: PENDING 2017-09-21 07:12:53,785 sleeping for 19.75s (attempt 3/10) 2017-09-21 07:13:13,555 attempt 4/10 2017-09-21 07:13:14,088 current task status: no status available at this point. state: PENDING 2017-09-21 07:13:14,088 sleeping for 28.62s (attempt 4/10) 2017-09-21 07:13:42,742 attempt 5/10 2017-09-21 07:13:43,046 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-21 07:13:43,254 unpacking tar archive at: mozilla-central-f7e9777221a3/testing/mozharness/ program finished with exit code 0 elapsedTime=74.046889 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 14 secs) (at 2017-09-21 07:13:42.840003) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 07:13:42.840526) ========= script_repo_revision: f7e9777221a34f9f23c2e4933307eb38b621b679 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 07:13:42.844285) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 07:13:42.844745) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 07:13:42.867615) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-21 07:13:42.868186) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:13:43 INFO - MultiFileLogger online at 20170921 07:13:43 in /builds/slave/test 07:13:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:13:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:13:43 INFO - {'append_to_log': False, 07:13:43 INFO - 'base_work_dir': '/builds/slave/test', 07:13:43 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:13:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:13:43 INFO - 'branch': 'Firefox-Non-PGO', 07:13:43 INFO - 'buildbot_json_path': 'buildprops.json', 07:13:43 INFO - 'code_coverage': False, 07:13:43 INFO - 'config_files': ('talos/linux_config.py',), 07:13:43 INFO - 'default_actions': ('clobber', 07:13:43 INFO - 'read-buildbot-config', 07:13:43 INFO - 'download-and-extract', 07:13:43 INFO - 'populate-webroot', 07:13:43 INFO - 'create-virtualenv', 07:13:43 INFO - 'install', 07:13:43 INFO - 'setup-mitmproxy', 07:13:43 INFO - 'run-tests'), 07:13:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:13:43 INFO - 'disable_ccov_upload': False, 07:13:43 INFO - 'disable_stylo': False, 07:13:43 INFO - 'download_minidump_stackwalk': True, 07:13:43 INFO - 'download_symbols': 'ondemand', 07:13:43 INFO - 'enable_stylo': False, 07:13:43 INFO - 'enable_webrender': False, 07:13:43 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 07:13:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:13:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:13:43 INFO - 'gecko_profile': False, 07:13:43 INFO - 'gecko_profile_interval': 0, 07:13:43 INFO - 'installer_path': 'installer.exe', 07:13:43 INFO - 'log_level': 'info', 07:13:43 INFO - 'log_name': 'talos', 07:13:43 INFO - 'log_to_console': True, 07:13:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:13:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:13:43 INFO - 'opt_config_files': (), 07:13:43 INFO - 'pip_index': False, 07:13:43 INFO - 'suite': 'dromaeojs-e10s', 07:13:43 INFO - 'system_bits': '32', 07:13:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:13:43 INFO - 'title': 'talos-linux64-ix-042', 07:13:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:13:43 INFO - 'use_talos_json': True, 07:13:43 INFO - 'verify': 'False', 07:13:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:13:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:13:43 INFO - 'work_dir': 'build'} 07:13:43 INFO - [mozharness: 2017-09-21 14:13:43.847064Z] Running clobber step. 07:13:43 INFO - Running pre-action listener: _resource_record_pre_action 07:13:43 INFO - Running main action method: clobber 07:13:43 INFO - rmtree: /builds/slave/test/build 07:13:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:13:44 INFO - Running post-action listener: _resource_record_post_action 07:13:44 INFO - [mozharness: 2017-09-21 14:13:44.796364Z] Finished clobber step (success) 07:13:44 INFO - [mozharness: 2017-09-21 14:13:44.796461Z] Running read-buildbot-config step. 07:13:44 INFO - Running pre-action listener: _resource_record_pre_action 07:13:44 INFO - Running main action method: read_buildbot_config 07:13:44 INFO - Using buildbot properties: 07:13:44 INFO - { 07:13:44 INFO - "project": "", 07:13:44 INFO - "product": "firefox", 07:13:44 INFO - "who": "archaeopteryx@coole-files.de", 07:13:44 INFO - "installer_path": "public/build/target.tar.bz2", 07:13:44 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 07:13:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 07:13:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 07:13:44 INFO - "stage_platform": "linux64", 07:13:44 INFO - "basedir": "/builds/slave/test", 07:13:44 INFO - "buildnumber": 850, 07:13:44 INFO - "platform": "ubuntu64_hw", 07:13:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:13:44 INFO - "slavebuilddir": "test", 07:13:44 INFO - "taskId": "Niw5oY3ZQUmr2nw9ULHC2Q", 07:13:44 INFO - "branch": "mozilla-central", 07:13:44 INFO - "script_repo_revision": "production", 07:13:44 INFO - "revision": "f7e9777221a34f9f23c2e4933307eb38b621b679", 07:13:44 INFO - "slavename": "talos-linux64-ix-042", 07:13:44 INFO - "repo_path": "mozilla-central" 07:13:44 INFO - } 07:13:44 INFO - Finding installer, test and symbols from parent task. 07:13:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Niw5oY3ZQUmr2nw9ULHC2Q'}, attempt #1 07:13:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Niw5oY3ZQUmr2nw9ULHC2Q'}, attempt #1 07:13:45 INFO - Task dependencies: OYLYgPYVTVKEo2gH9bptQA 07:13:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA'}, attempt #1 07:13:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 07:13:46 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.test_packages.json 07:13:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.crashreporter-symbols.zip 07:13:46 INFO - Running post-action listener: _resource_record_post_action 07:13:46 INFO - [mozharness: 2017-09-21 14:13:46.220221Z] Finished read-buildbot-config step (success) 07:13:46 INFO - [mozharness: 2017-09-21 14:13:46.220438Z] Running download-and-extract step. 07:13:46 INFO - Running pre-action listener: _resource_record_pre_action 07:13:46 INFO - Running main action method: download_and_extract 07:13:46 INFO - mkdir: /builds/slave/test/build/tests 07:13:46 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')]} 07:13:46 INFO - trying https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.test_packages.json 07:13:46 INFO - Downloading https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:13:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:13:46 INFO - Downloaded 989 bytes. 07:13:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:13:46 INFO - Using the following test package requirements: 07:13:46 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:13:46 INFO - u'common': [u'target.common.tests.zip'], 07:13:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:13:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:13:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:13:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:13:46 INFO - u'mozbase': [u'target.common.tests.zip'], 07:13:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:13:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:13:46 INFO - u'web-platform': [u'target.common.tests.zip', 07:13:46 INFO - u'target.web-platform.tests.tar.gz'], 07:13:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:13:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 07:13:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.common.tests.zip 07:13:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:13:46 INFO - Fetch https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.common.tests.zip into memory 07:13:47 INFO - Content-Length response header: 54741111 07:13:47 INFO - Bytes received: 54741111 07:13:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.talos.tests.zip 07:13:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:13:58 INFO - Fetch https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.talos.tests.zip into memory 07:13:59 INFO - Content-Length response header: 14509184 07:13:59 INFO - Bytes received: 14509184 07:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:59 INFO - trying https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 07:13:59 INFO - Downloading https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:13:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:14:00 INFO - Downloaded 61718892 bytes. 07:14:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 07:14:00 INFO - mkdir: /builds/slave/test/properties 07:14:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:14:00 INFO - Writing to file /builds/slave/test/properties/build_url 07:14:00 INFO - Contents: 07:14:00 INFO - build_url:https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 07:14:00 INFO - Running post-action listener: _resource_record_post_action 07:14:00 INFO - Running post-action listener: find_tests_for_verification 07:14:00 INFO - Running post-action listener: set_extra_try_arguments 07:14:00 INFO - [mozharness: 2017-09-21 14:14:00.993377Z] Finished download-and-extract step (success) 07:14:00 INFO - [mozharness: 2017-09-21 14:14:00.993512Z] Running populate-webroot step. 07:14:00 INFO - Running pre-action listener: _resource_record_pre_action 07:14:00 INFO - Running main action method: populate_webroot 07:14:01 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:14:01 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['tresize', 07:14:01 INFO - 'tcanvasmark']}, 07:14:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:14:01 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['dromaeo_css', 07:14:01 INFO - 'kraken']}, 07:14:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:14:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:14:01 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:14:01 INFO - 'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:14:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:14:01 INFO - 'tests': ['damp', 'tps']}, 07:14:01 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:14:01 INFO - 'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['damp', 'tps']}, 07:14:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:14:01 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['dromaeo_dom']}, 07:14:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:14:01 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['basic_compositor_video', 07:14:01 INFO - 'glvideo']}, 07:14:01 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:14:01 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:14:01 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:14:01 INFO - 'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['ts_paint_webext', 07:14:01 INFO - 'tp5o_webext']}, 07:14:01 INFO - 'other-e10s': {'tests': ['a11yr', 07:14:01 INFO - 'ts_paint', 07:14:01 INFO - 'tpaint', 07:14:01 INFO - 'sessionrestore', 07:14:01 INFO - 'sessionrestore_many_windows', 07:14:01 INFO - 'sessionrestore_no_auto_restore', 07:14:01 INFO - 'tabpaint']}, 07:14:01 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['a11yr', 07:14:01 INFO - 'ts_paint', 07:14:01 INFO - 'tpaint', 07:14:01 INFO - 'sessionrestore', 07:14:01 INFO - 'sessionrestore_many_windows', 07:14:01 INFO - 'sessionrestore_no_auto_restore', 07:14:01 INFO - 'tabpaint']}, 07:14:01 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 07:14:01 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 07:14:01 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['perf_reftest_singletons']}, 07:14:01 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['bloom_basic']}, 07:14:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:14:01 INFO - 'tsvgr_opacity', 07:14:01 INFO - 'tart', 07:14:01 INFO - 'tscrollx', 07:14:01 INFO - 'tsvg_static']}, 07:14:01 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['tsvgx', 07:14:01 INFO - 'tsvgr_opacity', 07:14:01 INFO - 'tart', 07:14:01 INFO - 'tscrollx', 07:14:01 INFO - 'tsvg_static']}, 07:14:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:14:01 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:14:01 INFO - 'talos_options': ['--disable-stylo'], 07:14:01 INFO - 'tests': ['tp5o']}, 07:14:01 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:14:01 INFO - 'talos_options': ['--mitmproxy', 07:14:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:14:01 INFO - '--firstNonBlankPaint'], 07:14:01 INFO - 'tests': ['tp6_google', 07:14:01 INFO - 'tp6_youtube', 07:14:01 INFO - 'tp6_amazon', 07:14:01 INFO - 'tp6_facebook']}, 07:14:01 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:14:01 INFO - 'talos_options': ['--disable-stylo', 07:14:01 INFO - '--mitmproxy', 07:14:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:14:01 INFO - '--firstNonBlankPaint'], 07:14:01 INFO - 'tests': ['tp6_google', 07:14:01 INFO - 'tp6_youtube', 07:14:01 INFO - 'tp6_amazon', 07:14:01 INFO - 'tp6_facebook']}, 07:14:01 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:14:01 INFO - 'talos_options': ['--stylo-threads=1', 07:14:01 INFO - '--mitmproxy', 07:14:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:14:01 INFO - '--firstNonBlankPaint'], 07:14:01 INFO - 'tests': ['tp6_google', 07:14:01 INFO - 'tp6_youtube', 07:14:01 INFO - 'tp6_amazon', 07:14:01 INFO - 'tp6_facebook']}, 07:14:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:14:01 INFO - 'talos_options': ['--xperf_path', 07:14:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:14:01 INFO - 'tests': ['tp5n']}, 07:14:01 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:14:01 INFO - 'talos_options': ['--disable-stylo', 07:14:01 INFO - '--xperf_path', 07:14:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:14:01 INFO - 'tests': ['tp5n']}}} 07:14:01 INFO - Running post-action listener: _resource_record_post_action 07:14:01 INFO - [mozharness: 2017-09-21 14:14:01.008748Z] Finished populate-webroot step (success) 07:14:01 INFO - [mozharness: 2017-09-21 14:14:01.008842Z] Running create-virtualenv step. 07:14:01 INFO - Running pre-action listener: _resource_record_pre_action 07:14:01 INFO - Running main action method: create_virtualenv 07:14:01 INFO - Creating virtualenv /builds/slave/test/build/venv 07:14:01 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:14:01 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 07:14:01 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:14:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:14:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:14:03 INFO - Installing distribute.............................................................................................................................................................................................done. 07:14:03 INFO - Installing pip...............done. 07:14:03 INFO - Return code: 0 07:14:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:14:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:14:04 INFO - Reading from file tmpfile_stdout 07:14:04 INFO - Output received: 07:14:04 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 07:14:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', '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 07:14:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:14:04 INFO - Using env: {'DISPLAY': ':0', 07:14:04 INFO - 'HOME': '/home/cltbld', 07:14:04 INFO - 'LANG': 'en_US.UTF-8', 07:14:04 INFO - 'LANGUAGE': 'en_US:en', 07:14:04 INFO - 'LOGNAME': 'cltbld', 07:14:04 INFO - 'MAIL': '/var/mail/cltbld', 07:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:04 INFO - 'MOZ_NO_REMOTE': '1', 07:14:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:04 INFO - 'NO_EM_RESTART': '1', 07:14:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:04 INFO - 'PWD': '/builds/slave/test', 07:14:04 INFO - 'SHELL': '/bin/bash', 07:14:04 INFO - 'SHLVL': '1', 07:14:04 INFO - 'TERM': 'linux', 07:14:04 INFO - 'TMOUT': '86400', 07:14:04 INFO - 'USER': 'cltbld', 07:14:04 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:04 INFO - '_': '/tools/buildbot/bin/python'} 07:14:05 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:14:05 INFO - Downloading/unpacking pip>=1.5 07:14:05 INFO - Running setup.py egg_info for package pip 07:14:05 INFO - warning: no files found matching 'pip/cacert.pem' 07:14:05 INFO - warning: no files found matching '*.html' under directory 'docs' 07:14:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:14:05 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:14:05 INFO - Installing collected packages: pip 07:14:05 INFO - Found existing installation: pip 1.1 07:14:05 INFO - Uninstalling pip: 07:14:05 INFO - Successfully uninstalled pip 07:14:05 INFO - Running setup.py install for pip 07:14:05 INFO - warning: no files found matching 'pip/cacert.pem' 07:14:05 INFO - warning: no files found matching '*.html' under directory 'docs' 07:14:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:14:05 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:14:05 INFO - Installing pip script to /builds/slave/test/build/venv/bin 07:14:05 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 07:14:05 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 07:14:05 INFO - Successfully installed pip 07:14:05 INFO - Cleaning up... 07:14:05 INFO - Return code: 0 07:14:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', '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 07:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:14:05 INFO - Using env: {'DISPLAY': ':0', 07:14:05 INFO - 'HOME': '/home/cltbld', 07:14:05 INFO - 'LANG': 'en_US.UTF-8', 07:14:05 INFO - 'LANGUAGE': 'en_US:en', 07:14:05 INFO - 'LOGNAME': 'cltbld', 07:14:05 INFO - 'MAIL': '/var/mail/cltbld', 07:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:05 INFO - 'MOZ_NO_REMOTE': '1', 07:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:05 INFO - 'NO_EM_RESTART': '1', 07:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:05 INFO - 'PWD': '/builds/slave/test', 07:14:05 INFO - 'SHELL': '/bin/bash', 07:14:05 INFO - 'SHLVL': '1', 07:14:05 INFO - 'TERM': 'linux', 07:14:05 INFO - 'TMOUT': '86400', 07:14:05 INFO - 'USER': 'cltbld', 07:14:05 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:05 INFO - '_': '/tools/buildbot/bin/python'} 07:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:05 INFO - Downloading/unpacking psutil>=3.1.1 07:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:14:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:14:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:14:07 INFO - Installing collected packages: psutil 07:14:07 INFO - Running setup.py install for psutil 07:14:07 INFO - building 'psutil._psutil_linux' extension 07:14:07 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 07:14:09 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 07:14:09 INFO - building 'psutil._psutil_posix' extension 07:14:09 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 07:14:09 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 07:14:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:14:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:14:09 INFO - Successfully installed psutil 07:14:09 INFO - Cleaning up... 07:14:09 INFO - Return code: 0 07:14:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:14:09 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')]} 07:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:09 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')]} 07:14:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:09 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 0x23981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', '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 07:14:09 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 07:14:09 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 07:14:09 INFO - Using env: {'DISPLAY': ':0', 07:14:09 INFO - 'HOME': '/home/cltbld', 07:14:09 INFO - 'LANG': 'en_US.UTF-8', 07:14:09 INFO - 'LANGUAGE': 'en_US:en', 07:14:09 INFO - 'LOGNAME': 'cltbld', 07:14:09 INFO - 'MAIL': '/var/mail/cltbld', 07:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:09 INFO - 'MOZ_NO_REMOTE': '1', 07:14:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:09 INFO - 'NO_EM_RESTART': '1', 07:14:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:09 INFO - 'PWD': '/builds/slave/test', 07:14:09 INFO - 'SHELL': '/bin/bash', 07:14:09 INFO - 'SHLVL': '1', 07:14:09 INFO - 'TERM': 'linux', 07:14:09 INFO - 'TMOUT': '86400', 07:14:09 INFO - 'USER': 'cltbld', 07:14:09 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:09 INFO - '_': '/tools/buildbot/bin/python'} 07:14:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:14:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:14:11 INFO - Installing collected packages: mozsystemmonitor 07:14:11 INFO - Running setup.py install for mozsystemmonitor 07:14:11 INFO - Successfully installed mozsystemmonitor 07:14:11 INFO - Cleaning up... 07:14:11 INFO - Return code: 0 07:14:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', '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 07:14:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:14:11 INFO - Using env: {'DISPLAY': ':0', 07:14:11 INFO - 'HOME': '/home/cltbld', 07:14:11 INFO - 'LANG': 'en_US.UTF-8', 07:14:11 INFO - 'LANGUAGE': 'en_US:en', 07:14:11 INFO - 'LOGNAME': 'cltbld', 07:14:11 INFO - 'MAIL': '/var/mail/cltbld', 07:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:11 INFO - 'MOZ_NO_REMOTE': '1', 07:14:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:11 INFO - 'NO_EM_RESTART': '1', 07:14:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:11 INFO - 'PWD': '/builds/slave/test', 07:14:11 INFO - 'SHELL': '/bin/bash', 07:14:11 INFO - 'SHLVL': '1', 07:14:11 INFO - 'TERM': 'linux', 07:14:11 INFO - 'TMOUT': '86400', 07:14:11 INFO - 'USER': 'cltbld', 07:14:11 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:11 INFO - '_': '/tools/buildbot/bin/python'} 07:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:11 INFO - Downloading/unpacking jsonschema==2.5.1 07:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:14:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:14:12 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 07:14:12 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 07:14:12 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 07:14:12 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 07:14:12 INFO - Downloading functools32-3.2.3-2.tar.gz 07:14:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:14:12 INFO - warning: no files found matching '*.txt' 07:14:12 INFO - no previously-included directories found matching 'build' 07:14:12 INFO - no previously-included directories found matching 'dist' 07:14:12 INFO - no previously-included directories found matching '.git*' 07:14:12 INFO - Installing collected packages: jsonschema, functools32 07:14:12 INFO - Running setup.py install for functools32 07:14:12 INFO - warning: no files found matching '*.txt' 07:14:12 INFO - no previously-included directories found matching 'build' 07:14:12 INFO - no previously-included directories found matching 'dist' 07:14:12 INFO - no previously-included directories found matching '.git*' 07:14:12 INFO - Successfully installed jsonschema functools32 07:14:12 INFO - Cleaning up... 07:14:12 INFO - Return code: 0 07:14:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:14: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')]} 07:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14: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')]} 07:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:12 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 0x23981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', '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 07:14:12 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 07:14:12 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 07:14:12 INFO - Using env: {'DISPLAY': ':0', 07:14:12 INFO - 'HOME': '/home/cltbld', 07:14:12 INFO - 'LANG': 'en_US.UTF-8', 07:14:12 INFO - 'LANGUAGE': 'en_US:en', 07:14:12 INFO - 'LOGNAME': 'cltbld', 07:14:12 INFO - 'MAIL': '/var/mail/cltbld', 07:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:12 INFO - 'MOZ_NO_REMOTE': '1', 07:14:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:12 INFO - 'NO_EM_RESTART': '1', 07:14:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:12 INFO - 'PWD': '/builds/slave/test', 07:14:12 INFO - 'SHELL': '/bin/bash', 07:14:12 INFO - 'SHLVL': '1', 07:14:12 INFO - 'TERM': 'linux', 07:14:12 INFO - 'TMOUT': '86400', 07:14:12 INFO - 'USER': 'cltbld', 07:14:12 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:12 INFO - '_': '/tools/buildbot/bin/python'} 07:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:14:13 INFO - Cleaning up... 07:14:13 INFO - Return code: 0 07:14:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', '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 07:14:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:14:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:14:13 INFO - Using env: {'DISPLAY': ':0', 07:14:13 INFO - 'HOME': '/home/cltbld', 07:14:13 INFO - 'LANG': 'en_US.UTF-8', 07:14:13 INFO - 'LANGUAGE': 'en_US:en', 07:14:13 INFO - 'LOGNAME': 'cltbld', 07:14:13 INFO - 'MAIL': '/var/mail/cltbld', 07:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:13 INFO - 'MOZ_NO_REMOTE': '1', 07:14:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:13 INFO - 'NO_EM_RESTART': '1', 07:14:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:13 INFO - 'PWD': '/builds/slave/test', 07:14:13 INFO - 'SHELL': '/bin/bash', 07:14:13 INFO - 'SHLVL': '1', 07:14:13 INFO - 'TERM': 'linux', 07:14:13 INFO - 'TMOUT': '86400', 07:14:13 INFO - 'USER': 'cltbld', 07:14:13 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:13 INFO - '_': '/tools/buildbot/bin/python'} 07:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:13 INFO - Downloading/unpacking blobuploader==1.2.4 07:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:14 INFO - Downloading blobuploader-1.2.4.tar.gz 07:14:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:14:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:14:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:14 INFO - Downloading docopt-0.6.1.tar.gz 07:14:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:14:15 INFO - Installing collected packages: blobuploader, requests, docopt 07:14:15 INFO - Running setup.py install for blobuploader 07:14:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:14:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:14:15 INFO - Running setup.py install for requests 07:14:15 INFO - Running setup.py install for docopt 07:14:15 INFO - Successfully installed blobuploader requests docopt 07:14:15 INFO - Cleaning up... 07:14:15 INFO - Return code: 0 07:14:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:14: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')]} 07:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14: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')]} 07:14:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:15 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 0x23981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', '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 07:14:15 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 07:14:15 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 07:14:15 INFO - Using env: {'DISPLAY': ':0', 07:14:15 INFO - 'HOME': '/home/cltbld', 07:14:15 INFO - 'LANG': 'en_US.UTF-8', 07:14:15 INFO - 'LANGUAGE': 'en_US:en', 07:14:15 INFO - 'LOGNAME': 'cltbld', 07:14:15 INFO - 'MAIL': '/var/mail/cltbld', 07:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:15 INFO - 'MOZ_NO_REMOTE': '1', 07:14:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:15 INFO - 'NO_EM_RESTART': '1', 07:14:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:15 INFO - 'PWD': '/builds/slave/test', 07:14:15 INFO - 'SHELL': '/bin/bash', 07:14:15 INFO - 'SHLVL': '1', 07:14:15 INFO - 'TERM': 'linux', 07:14:15 INFO - 'TMOUT': '86400', 07:14:15 INFO - 'USER': 'cltbld', 07:14:15 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:15 INFO - '_': '/tools/buildbot/bin/python'} 07:14:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:14:15 INFO - Running setup.py (path:/tmp/pip-ExTByb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:14:15 INFO - Running setup.py (path:/tmp/pip-8NKurv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:14:15 INFO - Running setup.py (path:/tmp/pip-WRA81E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:14:16 INFO - Running setup.py (path:/tmp/pip-yQ6LbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:14:16 INFO - Running setup.py (path:/tmp/pip-C0HiTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:14:16 INFO - Running setup.py (path:/tmp/pip-jbdBJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:14:16 INFO - Running setup.py (path:/tmp/pip-Tyr4VX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:14:16 INFO - Running setup.py (path:/tmp/pip-D9caRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:14:16 INFO - Running setup.py (path:/tmp/pip-UvYb3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:14:16 INFO - Running setup.py (path:/tmp/pip-Cm_w94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:14:16 INFO - Running setup.py (path:/tmp/pip-HwTART-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:14:16 INFO - Running setup.py (path:/tmp/pip-giNDs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:14:16 INFO - Running setup.py (path:/tmp/pip-3A4Kp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:14:16 INFO - Running setup.py (path:/tmp/pip-uIkkky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:16 INFO - Running setup.py (path:/tmp/pip-xYbo8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:14:16 INFO - Running setup.py (path:/tmp/pip-OX9M1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:14:16 INFO - Running setup.py (path:/tmp/pip-lLeT_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:14:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:14:17 INFO - Running setup.py install for manifestparser 07:14:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:14:17 INFO - Running setup.py install for mozcrash 07:14:17 INFO - Running setup.py install for mozdebug 07:14:17 INFO - Running setup.py install for mozdevice 07:14:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:14:17 INFO - Running setup.py install for mozfile 07:14:17 INFO - Running setup.py install for mozhttpd 07:14:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:14:17 INFO - Running setup.py install for mozinfo 07:14:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:14:17 INFO - Running setup.py install for mozInstall 07:14:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:14:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:14:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:14:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:14:17 INFO - Running setup.py install for mozleak 07:14:17 INFO - Running setup.py install for mozlog 07:14:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:14:18 INFO - Running setup.py install for moznetwork 07:14:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:14:18 INFO - Running setup.py install for mozprocess 07:14:18 INFO - Running setup.py install for mozprofile 07:14:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:14:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:14:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:14:18 INFO - Running setup.py install for mozrunner 07:14:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:14:18 INFO - Running setup.py install for mozscreenshot 07:14:18 INFO - Running setup.py install for moztest 07:14:18 INFO - Running setup.py install for mozversion 07:14:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:14:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:14:18 INFO - Cleaning up... 07:14:18 INFO - Return code: 0 07:14:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:18 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 0x23981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', '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 07:14:18 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 07:14:18 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 07:14:18 INFO - Using env: {'DISPLAY': ':0', 07:14:18 INFO - 'HOME': '/home/cltbld', 07:14:18 INFO - 'LANG': 'en_US.UTF-8', 07:14:18 INFO - 'LANGUAGE': 'en_US:en', 07:14:18 INFO - 'LOGNAME': 'cltbld', 07:14:18 INFO - 'MAIL': '/var/mail/cltbld', 07:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:18 INFO - 'MOZ_NO_REMOTE': '1', 07:14:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:18 INFO - 'NO_EM_RESTART': '1', 07:14:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:18 INFO - 'PWD': '/builds/slave/test', 07:14:18 INFO - 'SHELL': '/bin/bash', 07:14:18 INFO - 'SHLVL': '1', 07:14:18 INFO - 'TERM': 'linux', 07:14:18 INFO - 'TMOUT': '86400', 07:14:18 INFO - 'USER': 'cltbld', 07:14:18 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:18 INFO - '_': '/tools/buildbot/bin/python'} 07:14:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:14:18 INFO - Running setup.py (path:/tmp/pip-5pSBCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:14:19 INFO - Running setup.py (path:/tmp/pip-Qafjz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:14:19 INFO - Running setup.py (path:/tmp/pip-nUrnsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:14:19 INFO - Running setup.py (path:/tmp/pip-KaWMVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:14:19 INFO - Running setup.py (path:/tmp/pip-Vq5CKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:14:19 INFO - Running setup.py (path:/tmp/pip-zmlCKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:14:19 INFO - Running setup.py (path:/tmp/pip-HIFFh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:14:19 INFO - Running setup.py (path:/tmp/pip-MgYWVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:14:19 INFO - Running setup.py (path:/tmp/pip-gsJ1xD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:14:19 INFO - Running setup.py (path:/tmp/pip-4cgYFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:14:19 INFO - Running setup.py (path:/tmp/pip-Y72NWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:14:19 INFO - Running setup.py (path:/tmp/pip-zcsXfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:14:19 INFO - Running setup.py (path:/tmp/pip-3xSBZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:14:19 INFO - Running setup.py (path:/tmp/pip-40MKAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:14:19 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)) 07:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:19 INFO - Running setup.py (path:/tmp/pip-a0tYSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:20 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)) 07:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:14:20 INFO - Running setup.py (path:/tmp/pip-dI7Gf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:14:20 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)) 07:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:14:20 INFO - Running setup.py (path:/tmp/pip-p_bvPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:14:20 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)) 07:14:20 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)) 07:14:20 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)) 07:14:20 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)) 07:14:20 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)) 07:14:20 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)) 07:14:20 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)) 07:14:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:14: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 07:14: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 07:14: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 07:14: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 07:14:21 INFO - Downloading blessings-1.6.tar.gz 07:14:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:14:21 INFO - Installing collected packages: blessings 07:14:21 INFO - Running setup.py install for blessings 07:14:21 INFO - Successfully installed blessings 07:14:21 INFO - Cleaning up... 07:14:21 INFO - Return code: 0 07:14:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:14:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:14:21 INFO - Reading from file tmpfile_stdout 07:14:21 INFO - Current package versions: 07:14:21 INFO - argparse == 1.2.1 07:14:21 INFO - blessings == 1.6 07:14:21 INFO - blobuploader == 1.2.4 07:14:21 INFO - docopt == 0.6.1 07:14:21 INFO - functools32 == 3.2.3-2 07:14:21 INFO - jsonschema == 2.5.1 07:14:21 INFO - manifestparser == 1.1 07:14:21 INFO - mozInstall == 1.13 07:14:21 INFO - mozcrash == 1.0 07:14:21 INFO - mozdebug == 0.1 07:14:21 INFO - mozdevice == 0.51 07:14:21 INFO - mozfile == 1.2 07:14:21 INFO - mozhttpd == 0.7 07:14:21 INFO - mozinfo == 0.10 07:14:21 INFO - mozleak == 0.1 07:14:21 INFO - mozlog == 3.5 07:14:21 INFO - moznetwork == 0.27 07:14:21 INFO - mozprocess == 0.25 07:14:21 INFO - mozprofile == 0.28 07:14:21 INFO - mozrunner == 6.13 07:14:21 INFO - mozscreenshot == 0.1 07:14:21 INFO - mozsystemmonitor == 0.3 07:14:21 INFO - moztest == 0.8 07:14:21 INFO - mozversion == 1.4 07:14:21 INFO - psutil == 3.1.1 07:14:21 INFO - requests == 1.2.3 07:14:21 INFO - wsgiref == 0.1.2 07:14:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:14:21 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')]} 07:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:21 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')]} 07:14:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:21 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 0x23981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', '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 07:14:21 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 07:14:21 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 07:14:21 INFO - Using env: {'DISPLAY': ':0', 07:14:21 INFO - 'HOME': '/home/cltbld', 07:14:21 INFO - 'LANG': 'en_US.UTF-8', 07:14:21 INFO - 'LANGUAGE': 'en_US:en', 07:14:21 INFO - 'LOGNAME': 'cltbld', 07:14:21 INFO - 'MAIL': '/var/mail/cltbld', 07:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:21 INFO - 'MOZ_NO_REMOTE': '1', 07:14:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:21 INFO - 'NO_EM_RESTART': '1', 07:14:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:21 INFO - 'PWD': '/builds/slave/test', 07:14:21 INFO - 'SHELL': '/bin/bash', 07:14:21 INFO - 'SHLVL': '1', 07:14:21 INFO - 'TERM': 'linux', 07:14:21 INFO - 'TMOUT': '86400', 07:14:21 INFO - 'USER': 'cltbld', 07:14:21 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:21 INFO - '_': '/tools/buildbot/bin/python'} 07:14:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:22 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)) 07:14:22 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)) 07:14:22 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)) 07:14:22 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)) 07:14:22 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)) 07:14:22 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)) 07:14:22 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)) 07:14:22 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)) 07:14:22 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)) 07:14:22 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:14: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 07:14: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 07:14: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 07:14: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 07:14:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 07:14:24 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)) 07:14:24 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)) 07:14:24 INFO - Installing collected packages: simplejson 07:14:24 INFO - Running setup.py install for simplejson 07:14:24 INFO - building 'simplejson._speedups' extension 07:14:24 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 07:14:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 07:14:25 INFO - Successfully installed simplejson 07:14:25 INFO - Cleaning up... 07:14:25 INFO - Return code: 0 07:14:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:14: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')]} 07:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14: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')]} 07:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:25 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 0x23981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', '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 07:14:25 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 07:14:25 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 07:14:25 INFO - Using env: {'DISPLAY': ':0', 07:14:25 INFO - 'HOME': '/home/cltbld', 07:14:25 INFO - 'LANG': 'en_US.UTF-8', 07:14:25 INFO - 'LANGUAGE': 'en_US:en', 07:14:25 INFO - 'LOGNAME': 'cltbld', 07:14:25 INFO - 'MAIL': '/var/mail/cltbld', 07:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:25 INFO - 'MOZ_NO_REMOTE': '1', 07:14:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:25 INFO - 'NO_EM_RESTART': '1', 07:14:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:25 INFO - 'PWD': '/builds/slave/test', 07:14:25 INFO - 'SHELL': '/bin/bash', 07:14:25 INFO - 'SHLVL': '1', 07:14:25 INFO - 'TERM': 'linux', 07:14:25 INFO - 'TMOUT': '86400', 07:14:25 INFO - 'USER': 'cltbld', 07:14:25 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:25 INFO - '_': '/tools/buildbot/bin/python'} 07:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:14:25 INFO - Cleaning up... 07:14:25 INFO - Return code: 0 07:14:25 INFO - Running post-action listener: _resource_record_post_action 07:14:25 INFO - Running post-action listener: _start_resource_monitoring 07:14:25 INFO - Starting resource monitoring. 07:14:25 INFO - [mozharness: 2017-09-21 14:14:25.549805Z] Finished create-virtualenv step (success) 07:14:25 INFO - [mozharness: 2017-09-21 14:14:25.550799Z] Running install step. 07:14:25 INFO - Running pre-action listener: _resource_record_pre_action 07:14:25 INFO - Running main action method: install 07:14:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:14:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:14:25 INFO - Reading from file tmpfile_stdout 07:14:25 INFO - Detecting whether we're running mozinstall >=1.0... 07:14:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:14:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:14:25 INFO - Reading from file tmpfile_stdout 07:14:25 INFO - Output received: 07:14:25 INFO - Usage: mozinstall [options] installer 07:14:25 INFO - Options: 07:14:25 INFO - -h, --help show this help message and exit 07:14:25 INFO - -d DEST, --destination=DEST 07:14:25 INFO - Directory to install application into. [default: 07:14:25 INFO - "/builds/slave/test"] 07:14:25 INFO - --app=APP Application being installed. [default: firefox] 07:14:25 INFO - mkdir: /builds/slave/test/build/application 07:14:25 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'] 07:14:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:14:43 INFO - Reading from file tmpfile_stdout 07:14:43 INFO - Output received: 07:14:43 INFO - /builds/slave/test/build/application/firefox/firefox 07:14:43 INFO - Running post-action listener: _resource_record_post_action 07:14:43 INFO - [mozharness: 2017-09-21 14:14:43.708384Z] Finished install step (success) 07:14:43 INFO - [mozharness: 2017-09-21 14:14:43.708529Z] Running setup-mitmproxy step. 07:14:43 INFO - Running pre-action listener: _resource_record_pre_action 07:14:43 INFO - Running main action method: setup_mitmproxy 07:14:43 INFO - Skipping: mitmproxy is not required 07:14:43 INFO - Running post-action listener: _resource_record_post_action 07:14:43 INFO - [mozharness: 2017-09-21 14:14:43.709055Z] Finished setup-mitmproxy step (success) 07:14:43 INFO - [mozharness: 2017-09-21 14:14:43.709180Z] Running run-tests step. 07:14:43 INFO - Running pre-action listener: _resource_record_pre_action 07:14:43 INFO - Running pre-action listener: _set_gcov_prefix 07:14:43 INFO - Running main action method: run_tests 07:14:43 WARNING - Try message not found. 07:14:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:14:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:14:43 INFO - Python 2.7.3 07:14:43 INFO - Return code: 0 07:14:43 INFO - grabbing minidump binary from tooltool 07:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:43 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2708090>, '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 0x2708d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2702f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:14:43 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:14:43 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:14:43 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:14:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:14:43 INFO - Return code: 0 07:14:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:14:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:14:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:14:43 INFO - ENV: RUST_BACKTRACE is now full 07:14:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:14:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:14:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 07:14:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-042 --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 07:14:43 INFO - Using env: {'DISPLAY': ':0', 07:14:43 INFO - 'HOME': '/home/cltbld', 07:14:43 INFO - 'LANG': 'en_US.UTF-8', 07:14:43 INFO - 'LANGUAGE': 'en_US:en', 07:14:43 INFO - 'LOGNAME': 'cltbld', 07:14:43 INFO - 'MAIL': '/var/mail/cltbld', 07:14:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:14:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:43 INFO - 'MOZ_NO_REMOTE': '1', 07:14:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:43 INFO - 'NO_EM_RESTART': '1', 07:14:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:43 INFO - 'PWD': '/builds/slave/test', 07:14:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:14:43 INFO - 'RUST_BACKTRACE': 'full', 07:14:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:14:43 INFO - 'SHELL': '/bin/bash', 07:14:43 INFO - 'SHLVL': '1', 07:14:43 INFO - 'TERM': 'linux', 07:14:43 INFO - 'TMOUT': '86400', 07:14:43 INFO - 'USER': 'cltbld', 07:14:43 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:43 INFO - '_': '/tools/buildbot/bin/python'} 07:14:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 07:14:44 INFO - mozversion application_buildid: 20170921132403 07:14:44 INFO - mozversion application_changeset: f7e9777221a34f9f23c2e4933307eb38b621b679 07:14:44 INFO - mozversion application_display_name: Nightly 07:14:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:14:44 INFO - mozversion application_name: Firefox 07:14:44 INFO - mozversion application_remotingname: firefox 07:14:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:14:44 INFO - mozversion application_vendor: Mozilla 07:14:44 INFO - mozversion application_version: 57.0a1 07:14:44 INFO - mozversion platform_buildid: 20170921132403 07:14:44 INFO - mozversion platform_changeset: f7e9777221a34f9f23c2e4933307eb38b621b679 07:14:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:14:44 INFO - mozversion platform_version: 57.0a1 07:14:44 INFO - using testdate: 1506003284 07:14:44 INFO - actual date: 1506003284 07:14:44 INFO - starting webserver on 'localhost:50563' 07:14:44 INFO - SUITE-START | Running 2 tests 07:14:44 INFO - TEST-START | dromaeo_css 07:14:44 INFO - Initialising browser for dromaeo_css test... 07:14:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50563/getInfo.html -profile /tmp/tmpBHo4K8/profile 07:14:44 INFO - TEST-INFO | started process 3574 (/builds/slave/test/build/application/firefox/firefox http://localhost:50563/getInfo.html) 07:14:52 INFO - TEST-INFO | 3574: exit 0 07:14:52 INFO - Browser initialized. 07:14:52 INFO - Running cycle 1/1 for dromaeo_css test... 07:14:52 INFO - Using env: {'DISPLAY': ':0', 07:14:52 INFO - 'HOME': '/home/cltbld', 07:14:52 INFO - 'JSGC_DISABLE_POISONING': '1', 07:14:52 INFO - 'LANG': 'en_US.UTF-8', 07:14:52 INFO - 'LANGUAGE': 'en_US:en', 07:14:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:14:52 INFO - 'LOGNAME': 'cltbld', 07:14:52 INFO - 'MAIL': '/var/mail/cltbld', 07:14:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:14:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:14:52 INFO - 'MOZ_CRASHREPORTER': '1', 07:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:14:52 INFO - 'MOZ_NO_REMOTE': '1', 07:14:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:14:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:52 INFO - 'NO_EM_RESTART': '1', 07:14:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:52 INFO - 'PWD': '/builds/slave/test', 07:14:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:14:52 INFO - 'RUST_BACKTRACE': 'full', 07:14:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:14:52 INFO - 'SHELL': '/bin/bash', 07:14:52 INFO - 'SHLVL': '1', 07:14:52 INFO - 'TERM': 'linux', 07:14:52 INFO - 'TMOUT': '86400', 07:14:52 INFO - 'USER': 'cltbld', 07:14:52 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:52 INFO - '_': '/tools/buildbot/bin/python'} 07:14:52 INFO - TEST-INFO | started process 3808 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBHo4K8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:14:53 INFO - PID 3808 | 07:14:53 INFO - PID 3808 | (/builds/slave/test/build/application/firefox/firefox:3857): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:14:53 INFO - PID 3808 | 07:15:04 INFO - PID 3808 | RSS: Main: 154284032 07:15:04 INFO - PID 3808 | 07:18:01 INFO - PID 3808 | Cycle 1(1): loaded http://localhost:50563/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50563/tests/dromaeo/cssquery-ext.html) 07:18:02 INFO - PID 3808 | RSS: Main: 154755072 07:18:02 INFO - PID 3808 | 07:21:04 INFO - PID 3808 | Cycle 1(1): loaded http://localhost:50563/tests/dromaeo/cssquery-ext.html (next: http://localhost:50563/tests/dromaeo/cssquery-jquery.html) 07:21:05 INFO - PID 3808 | RSS: Main: 154873856 07:21:05 INFO - PID 3808 | 07:24:07 INFO - PID 3808 | Cycle 1(1): loaded http://localhost:50563/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50563/tests/dromaeo/cssquery-mootools.html) 07:24:07 INFO - PID 3808 | RSS: Main: 156938240 07:24:07 INFO - PID 3808 | 07:27:09 INFO - PID 3808 | Cycle 1(1): loaded http://localhost:50563/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50563/tests/dromaeo/cssquery-prototype.html) 07:27:10 INFO - PID 3808 | RSS: Main: 154816512 07:27:10 INFO - PID 3808 | 07:30:13 INFO - PID 3808 | Cycle 1(1): loaded http://localhost:50563/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50563/tests/dromaeo/cssquery-yui.html) 07:30:13 INFO - PID 3808 | RSS: Main: 156405760 07:30:13 INFO - PID 3808 | 07:33:15 INFO - PID 3808 | Cycle 1(1): loaded http://localhost:50563/tests/dromaeo/cssquery-yui.html (next: http://localhost:50563/tests/dromaeo/cssquery-dojo.html) 07:33:15 INFO - PID 3808 | RSS: Main: 158953472 07:33:15 INFO - PID 3808 | 07:33:15 INFO - PID 3808 | __start_tp_report 07:33:15 INFO - PID 3808 | _x_x_mozilla_page_load 07:33:15 INFO - PID 3808 | _x_x_mozilla_page_load_details 07:33:15 INFO - PID 3808 | |i|pagename|runs| 07:33:15 INFO - PID 3808 | |0;dojo.html;3128.7340576051033;3178.7457003439727;3202.08740508955;3209.9518507222383;3210.919727006826;6247.6251424914535;6341.7146228419715;6389.169407976962;6422.518311126667;6513.869722605551;6278.686065696708;6283.7486500540035;6330.620162790225;6365.077063825743;6370.331115232903;5764.365919748824;5810.854728631797;5832.329282132567;5836.270466191726;5901.114832775075;5903.645781253139;5913.14259432382;5925.377835327293;5966.224390829186;5978.40215978403;5893.911591326112;5902.321233058189;5936.762529498837;5974.163617093589;5990.670513121777;5338.466153384669;5639.351474580435;5758.62568933018;5820.068788993741;5874.295084589834;5772.538196944234;5776.855578610526;5778.816276324022;5855.443732845373;5862.794802181903;6274.937250627527;6339.714712837934;6347.492200623939;6394.944834102367;6414.294427612959;6756.2568117507035;6778.728850845961;6789.321067893221;6839;6841.931580684229;6885.070515480396;6893.827654308632;6918.100646915919;6924.826879328007;6928.376446119873;6205.7517699291975;6251.374862513758;6334.144890439778;6351.618902865843;6412.166418365629;7013.05323781283;7032;7099.219085900547;7106.2183159852375;7128.108986376703;6699.9665001674675;6711.261761206263;6731;6771.3905748482875;6781.42357899584;6598.73605055802;6687.4984376171005;6707.966460167668;6712.093867327946;6739.191297044386;6534.183227096613;6574.408303252731;6755.864882702318;6797.320267973163;6831.04365388846;6829.624370659562;6873.5875847449315;6879.140107486564;6907.309269073153;6922.861542769117;5334.279872217201;5335.146376579729;5404.351477822686;5422.48486393792;5422.91865622016;4603.424571928509;4614.192516309699;4664.183767840615;4702.929456058162;4718.386609740712;5768.884622307531;5843.035899076604;5851.590388672837;5852.824415267549;5853.678047707417;6825.897611535831;6827.180738311435;6901.240863505011;6904.344087311697;6925.792226233221;5788.5369170466265;5790.189373487715;5838.795642152504;5855.824325270249;5877.265341832271;5669.688167150846;5779.075347944308;5795.13073039039;5816.331121920949;5887.4406931341455;6473.805785826433;6522.119513865662;6539.574927629689;6564.606123632598;6575.506836987245;6820.522563420513;6839;6854.760083397057;6910.136232970879;6911.723531058902;6573.1454910861285;6583.670816459254;6585.670716464062;6689.130413046273;6695.631740254333;6714.798556043326;6715.026321183499;6718.428933540704;6790.83022924411;6825.69284382194;6833.521653484208;6874.381305682513;6935.133108361455;6940;6952.930470695228;6506.41442270198;6512;6586.967065164644;6587.703553340016;6592.110065141336;6531.150950376421;6566.606003639797;6635.900461493178;6661.600303981777;6665;6536.346365363425;6588.143541339595;6624;6636.0377745225705;6636.867262654817;6587.670616469061;6668.099806526056;6668.499862510428;6715.3620406061345;6744.898826517704;6356.269029061717;6404.583702059351;6411.4870810336;6418.614883107029;6534.477241820738 07:33:15 INFO - PID 3808 | |1;ext.html;879.4547380624014;898.043583583484;905.5155491811882;906.5286051253348;910.3627460777453;41196.38205426921;41650.166996660046;41888;42049.99999999992;42067.57932420675;38718.22563548727;38932;38939.22121557567;39049.41425878607;39114.60885391145;7165.390941769942;7239.529430586995;7256.455765817558;7259.128904531437;7264.38252748517;7085.7165713371405;7161;7166;7177.712891484334;7180.353768160864;7139.464540159483;7157.069580954469;7158.105236845395;7784.0659120905575;7907.5255484670815;7814.413918956073;7846.489978151402;7864.05631324239;7864.882026769575;7867;7781.3385862201785;7835.686572537149;7864.252895974873;7866;7867.331276841418;38289.425658615146;38488.615113848784;38586.421203681966;39170.412443813366;39518.802405987786;3489.616142224353;3545.556805399325;3546.6098729139776;3548.8048157351336;3553.4136867416846;33081.17297067593;33100.83449582737;33222.83388583066;33346.83326583376;33361.332773344395;5149.382074151088;5159.638825282232;5187.922181167248;5228.503292187236;5259.105951988133;193845;195003.99999999857;195306;195617;196013;40654;41433.79283103565;45935.31097033547;46647.533524665;47650.76174619105;49622;49823.75088124609;49931.75034124806;49941.500584993686;49983;13821.723565528633;13872.237026963614;13983.930080349737;14036.157830530201;14054;11079.390633515233;11156.497957591928;11197.832032519518;11303.660890173143;11311.377874216832;13544.796828047587;13565.321733913264;13603.455861765617;13679.863201368058;13773.311334433438;5420.71763306983;5455.454454554513;5531.502164805106;5535.142052981794;5556.77772889088;970.7621632699893;1007.2143727892255;1016.8271393863062;1020.1481762728057;1022.4018948914819;22676.886615567146;22718.659220111713;23002.769972300397;23095;23230;1087.478010554938;1103.7957977773976;1107.8449017137607;1109.362116782837;1111.4220605285368;11939.462724177432;12033.518659253707;12175.939120304341;12314.014878809852;12414.565490207799;233160;233523;234055;234064;234067;52344.214836776715;52563.474365256625;52690;52724.47275527272;52790.73604631952;1436.9856301436853;1443.5092068696483;1449.8550144985418;1465.340596731475;1468.7943687883492;22688.432789179744;22725.545489090455;22845.08619655195;22935.885320573958;22986.195483158008;36672.63327366799;36702.63297366992;36704.63295367012;36719.63280367162;36746.816265918496;188737;189842;189914;190319;190585;33346.66653333532;35135.12162195965;35511.82244088763;35627.10932226715;35720.82139589285;44244.11511769811;45321;45508.08983820371;45510;45775.313370298805;32911.34177316488;33048;33338.833305833316;33380;33684.66315336815;32684.50973235356;32803;33060.66939330672;33123.66876331302;33782.83108584442;36144;37719.81140094282;37753.24493510169;37800.43299350567;37866.8106659465;32054;32239.838800805846;32303.676963231006;32438.513422299155;32996;24043.398915027265;24058.39854003664;24263.51472970566;24666.753332466447;24673 07:33:15 INFO - PID 3808 | |2;jquery.html;591.8520369907524;592.9288485381754;594.1355327997763;597.2086984745213;598.7156100852096;22086.447838804037;22933.770662293395;22978.770212297874;23001.539969200607;23006.88496557519;22454.214102506416;22635.434114147156;22779.202727904525;22828.97269622867;24041.15855945042;6513.967430162843;6740.022696708974;6740.629265390402;6742.22464416591;6743.730250789962;6439;6601.603903765766;6674.132362792854;6685.030670552767;6707.1616047994;6568.244651865025;6668.133142691443;6673.399394054534;6676.799696009128;6684.3315668433015;6634.104395906563;6656.534042617019;6931.3415225553745;7161.427085833121;7223.674934627902;6961.095057642525;6974.163100427981;7018.75434359795;7020.7893763187185;7033.788986330367;30212.395752084834;30807.38385232305;30994.845025774954;31002.844985774926;31132;2023.3221870673342;2071.461420030788;2074.481379655086;2077.5948690005457;2085.968710469329;20207;21685.783142168533;21918.89040554803;21968.780312196992;22112;2561.8078644101765;2584.6510721052605;2618.751218634227;2622.7246139155304;2642.656454660901;108347.45826270818;113914;137603;143863.28068359592;144018.55981440155;20060;20773.792262077488;21762.347129586138;21952.670709939364;22639.773602264097;44201;44420;44435.77782111133;44456.77771611186;44678.32982505265;10654.467276636136;10658.733531661615;10796.650418697664;10863.18526110545;10971.23201375912;8704.651813927372;8829.028806831244;8852.20330170275;8891.155340242667;8912.509811960275;11366.204365694488;11374;11633.825492617618;11640;11701.590444334408;3753.868614598476;3863.7874916879277;3952.0457238447316;4029.7582145071387;4096.815643296059;918.9264858811279;932.7668082979255;949.027247071757;958.4632605740808;962.4332542087838;18651.533711657317;18952;19153.80846191548;19345.90327048356;19372.321968731027;994.6966175316453;1005.5073014222978;1007.9244056695777;1008.7831116310028;1011.676263595657;10527.21045921559;10762.730931726614;10830.891691082988;10832.458377081115;10907.563697452013;435173;438257;440006;477578;489691;106141.46929265304;107355;107860;110389;112397;994.5574219472265;1033.4987531047436;1050.1231471721198;1093.5407129005837;1117.4747868501954;20094.19623215055;20216.79783202149;20510.589788203855;20651.4837129073;20652.896735516228;54391.18413223726;55697.88604227974;56281;56386.43613563812;57569.71215143897;67771.3222867765;67783.66108169427;68081.65959170173;68430.3156968424;68465;19353.8064619352;19960.20159193616;20043.098060587017;20045.298414555422;20082.598348032665;23166.652500212847;23191.536169276857;23224.3032709019;23286.184983525494;23528.176513821934;18062.277508900024;18147.637047259246;18217.271309147487;18468.44594662162;18718.625627487647;16382.75425868589;16676.332946682483;17139.057351845768;17246.396376126777;17263.654726905643;18388.44834654909;18504.537386565444;18750.906245468686;18790.906045469685;18940.715889261948;19313.61372772565;19345.70981435308;19385.90307048512;19451.124699388278;19559.511012224808;17825.821741782416;17915.910420448337;17978.5505362367;18148.72776908374;18293.359732409303 07:33:15 INFO - PID 3808 | |3;mootools.html;1348.467355394619;1391.5686137297446;1400.2858542143504;1418.7233489469554;1424.8788852947498;15286.617834554141;15952;15956.601084972881;15975;15982;15604.609884752887;15723.60690982726;15723.842761572381;15799.131047792367;15838.049717016987;2759.7240275972345;2812.5078111330486;2842.9147125586164;2848.9287767805763;2853.443578502193;2774.8473833939124;2802.579613058037;2808.101407549585;2819.6757372902175;2821.153653903831;2709.336212627909;2820.830750154997;2832.6884042755282;2834.8015638905285;2840.2757296889263;2786.637737094165;2793.4552762211174;2798.594203840442;2828.7454129128478;2832.22113918673;2690.5829596412586;2740.630014947996;2740.9725902740915;2754.724527547229;2762.958555621647;15188.392464301414;15505.457308994244;15579.532614021597;15647;15658.686826263525;8223.794405139859;8505;8519.275861551776;8519.701810436667;8554.315654747605;5648.491635752761;5723.713814309268;5772.9711351442975;5774.133879918004;5867.677277749722;1866.5893503429359;1890.8203720646516;1918.7985261547615;1924.393815947972;1932.3043704266454;27450;27522.724772752215;27707;27719.584206236923;27732.029378971638;6965;7145.392641625416;7202.639868006579;7316.304951029643;7422.331990120915;18043.09784510769;18058.819411805976;18074.548136296435;18083.367082152326;18097.276108955757;7853.568053756984;7960;7966.681332746625;7970.7210247642215;7973.601319934096;6851.3491218334175;7305.305995930378;7324.08448943882;7325.633718314063;7333.48665593414;5237.73811309433;5411.621186516906;5442.455754424526;5449.209864569694;5470.7264636768;1428.371516532743;1456.6940942402002;1480.3412481445653;1484.0724547158027;1497.1840347010905;885.2829208341135;896.1844721303582;910.7814124610046;911.7720569857536;916.9037251088673;8259.38054645904;8359.15788631868;8480.15198480147;8559.700410485602;8639.78400539979;718.7879575525233;727.7925791149497;728.4791374167496;729.0813574895669;808.3452403553141;5225.268462415265;5343.145096784574;5346.786128554814;5422.91865622016;5545.279113715272;21661.35015949524;22620;22665.660015099787;22888.084476621083;22939.311820645406;21077.683834742493;22254.554908902057;22427.32718018462;22510.98700558479;22518;11237.38194399316;11432.714182145346;11436.94281528587;11509.539618415338;11516.884831151749;7805.2194780521495;8392.118827523022;8579.442336248125;8591.914080859111;8679.17547832955;18800.34198803035;18832.24671013144;18993.33523326677;19293.421197364103;19351.709724353863;19215.80784192196;19456.221751130364;19489.317873874345;19549.902250488656;19588.90205548963;10829.67510974672;11030;11032.393218373067;11034.944825275823;11043.723906902393;13944.30278486092;14030.508932187324;14082.577522674335;14137.151770893779;14168.362423691166;11448.484818183368;11589.188756786943;11669.35818529989;11689.766204675689;11920.940395297968;10827.66216907254;10965.342079474938;11068.667939961813;11392.43037848121;11495.942520287344;9829.115379615869;9891.208703303575;9943.25425593069;9945.701628951143;9959.800803984024;7078.646067696697;7292.48952573336;7301.233370496234;7319.304666056929;7332;7349.265073492608;7385.852282954418;7396.519226250277;7413.592252425952;7426.702931882664 07:33:15 INFO - PID 3808 | |4;prototype.html;517.2913109040613;518.9636725429222;537.8413368056423;538.3459097196906;549.3133583021223;6180.196574445322;6214.720337584814;6256.718447669854;6330.620162790236;6356.459700925427;6143.631382117076;6258.12386265922;6263.248410190772;6277.2467303923595;6284.5600807943465;5178.430372659004;5196.480351964792;5211.140161873286;5213.713245771481;5215.713135777531;4814.9037019259595;5174.508421699932;5187.377514698242;5222.791088356461;5248.160294352904;4864.586510146642;4901.53435423636;4939.53074457926;4942.061008408409;4945.134601444751;4897.363342765453;4916.385451818523;4918.655694101414;4945.381827271609;4974.37820272466;4381.802818873159;4412.205802955466;4439.622632076277;4446.622037126848;4462.531434199394;6041.154238406627;6064.727087281083;6065.423784740487;6099.603525770811;6106.740506012804;5642.943570564283;5647.152927060933;5653.689047102408;5706.9714651426475;5737.598368114233;6427.2287325520765;6503.544751867371;6515.165902832283;6542.476601871839;6564.997675708907;4693;4866.343043689128;4890.039068354608;4904.117258893398;4920.901581968376;6687.331266873322;6706.094677218562;6833.086735714008;6838.794836154923;6849.698557274151;4964.950350496521;5003.149464591028;5003.449620541737;5013.924791128136;5018.949810501848;5042.672226305279;5078.720670363092;5086.54221120101;5097.949020509747;5117.181250999822;5644.121498036031;5653.321601407775;5689.374168841424;5695.943040569541;5714.285714285715;6507.8698426031215;6514.250861150934;6546.476281897437;6615.048560674612;6621.370969757865;1760.0935518208132;1769.080078359246;1773.6364045370667;1776.7385156539003;1784.6698360803293;1128.4527004402858;1187.5724739093919;1194.9163558550817;1211.6486218996567;1216.6410908781932;5633.746481408347;5648.6610803351105;5648.9717551412805;5690.9146362804595;5698.860227954426;6624.443255834875;6643.634600097041;6688.665566721645;6720.6651709647;6743.15710536183;5567.693776842312;5593.552515798727;5594.9720251398485;5608.158776183605;5610.49505544503;3494.0559108861185;3498.9125271867897;3509.78941263525;3522.6125126236093;3536.504889315498;6269.962262264782;6276.937230627726;6282.277538083087;6294.590851594632;6302.4958003359625;6506.4469520091325;6682.164729408824;6689.665516724144;6714;6747.628880411625;6296.2759282681245;6346.587471814318;6365.490760739037;6391.328910464427;6418.486521078303;6014.699265036818;6430.967845160744;6491.675416229265;6518.57629254097;6623.595309119381;6412.647304398303;6489.221293444817;6498.512611554058;6555.311692272243;6584.703688333941;6284.277308109443;6371.534547054296;6418.711157997995;6453.708608609737;6532.673366331759;5428.59285553593;5473.726313684379;5499.1476321170285;5573.554115670818;5590.5248053915875;6478.255000674794;6564.409203171737;6615.933840661532;6792;6895.275996020346;6306.108167549625;6480.70836812354;6481.125048118443;6522.478201743944;6571;6420.394901274682;6453.483721302378;6459;6468.5472016958365;6497.505573718166;6399.968000159969;6417.454516366161;6418.4544313731985;6526.836829079311;6533.379328963741;6525.608463492205;6552.6068435894;6569.638669873012;6628.337166283333;6643.435307998874;6251.874662560601;6252.624842509464;6316.431521163117;6331.968340158454;6401.167848179707 07:33:15 INFO - PID 3808 | |5;yui.html;899.6266549382007;904.8461761500545;921.2952091649887;924.8381533231687;925.4863550729342;18798.718019229724;19317.903410482926;19375.903120484396;19429.222831086776;19435.22259109634;18352.90823545884;18749.718754218662;18999.43001709951;19163.329283475083;19395.806041939577;958.3100167879134;964.864918911353;965.2374624046995;966.5119114847028;967.0281367225928;962.4610218277752;995.1590905684722;999.1008092716577;1002.3284399452384;1003.032074048543;1042.2235434601232;1044.0655613226158;1046.7383154211448;1046.7435478307825;1048.4652827058217;1041.536516250269;1045.9529321180564;1047.261680515235;1047.6804574604741;1048.3709774135534;720.1286443403488;731.2322061835052;733.7175187552778;733.9559626422432;772.6214155064034;20687.689684654742;20974.790252097435;21043.894780526152;21064.5787084259;21069.367918962456;21418.678719819214;21501.24745633895;22174.77825221743;22421.887890560607;22500.32499025032;17683.911580442014;17797.377091801725;17832.10839458022;17959.551011224692;18059.367922122663;3705.6294370562728;3728.7949162796035;3742.644448777362;3744.8876533704033;3748.5876553579083;133996.33001834928;135278;135429.32285338608;135785;136015;16732.24704888283;16906.830931690525;16991;17224;17329.393471228446;31330.21674458111;32166.678333216838;32206.83896580549;32208.677913221036;32514;8456.323494120456;8560.186782255676;8561;8562.48625082497;8568.10034946334;4351.19502891966;4388.144311859221;4470.396496472935;4486.371907932892;4496.865094047183;5521.199426083195;5577.358603760538;5577.414371491016;5598.468145526169;5619.241402410704;1005.7234260578399;1038.2991480750463;1041.739565108723;1050.1179009631946;1056.028453822485;639.3670266436195;646.4408286831857;646.7251418147363;646.747768370336;659.5416185750953;11170.93876081771;11617.88382116168;11799;11935.896666866678;12100.878991210151;689.407109885498;690.5442408010689;694.7082225465317;706.6749295324106;712.344642928507;5460.453954604508;5502.58730595207;5508.20131080991;5536.674596669328;5678.858028549237;117294.41352793181;119173.40413298052;119677.40161299138;120036;123574;107516;112180.43909780562;112450;113551;114109.14727131889;96313.51843240739;97790.5110474443;98075.01924980801;99626;99674;10309;10496.317739346918;10516.947415262875;10522.473876306001;10748.946255268673;44728;45021.099578008914;45692;46464.07071858612;46634.76682616565;55929.440705592424;55971.22661538143;57144.71427642835;57681.42318576929;58238.4176158233;15750.212489375257;15941.12323822201;16096.67806643884;16103.67792644117;16248.431304904745;15849.68300634004;15989.120598366737;16185.514434566981;16367;16458.09480478585;15036.473723419627;15294.617634559225;15359.462418815288;15489.612759681098;15508.5347439577;15127.621809454851;15220.391184352502;15505.534833955;15519.301631426839;15574.766378504559;14336.641583960485;14450.132992020512;14462.20457874827;14515;14553.417863285349;11547.191696581158;11639.59261425846;11801.409929503663;11983.64049078529;12296.75406491883;11150.553977840795;11374.260673056226;11445.370504622326;11449.14131440162;11462.94268528652 07:33:15 INFO - PID 3808 | __end_tp_report 07:33:15 INFO - PID 3808 | __start_cc_report 07:33:15 INFO - PID 3808 | _x_x_mozilla_cycle_collect,297 07:33:15 INFO - PID 3808 | __end_cc_report 07:33:15 INFO - PID 3808 | __startTimestamp1506004395800__endTimestamp 07:33:15 INFO - PID 3808 | 07:33:15 INFO - PID 3808 | ------- Summary: start ------- 07:33:15 INFO - PID 3808 | Number of tests: 6 07:33:15 INFO - PID 3808 | 07:33:15 INFO - PID 3808 | [#0] dojo.html Cycles:165 Average:6257.45 Median:6522.12 stddev:760.33 (11.7%) stddev-sans-first:721.96 07:33:15 INFO - PID 3808 | Values: 3128.7 3178.7 3202.1 3210.0 3210.9 6247.6 6341.7 6389.2 6422.5 6513.9 6278.7 6283.7 6330.6 6365.1 6370.3 5764.4 5810.9 5832.3 5836.3 5901.1 5903.6 5913.1 5925.4 5966.2 5978.4 5893.9 5902.3 5936.8 5974.2 5990.7 5338.5 5639.4 5758.6 5820.1 5874.3 5772.5 5776.9 5778.8 5855.4 5862.8 6274.9 6339.7 6347.5 6394.9 6414.3 6756.3 6778.7 6789.3 6839.0 6841.9 6885.1 6893.8 6918.1 6924.8 6928.4 6205.8 6251.4 6334.1 6351.6 6412.2 7013.1 7032.0 7099.2 7106.2 7128.1 6700.0 6711.3 6731.0 6771.4 6781.4 6598.7 6687.5 6708.0 6712.1 6739.2 6534.2 6574.4 6755.9 6797.3 6831.0 6829.6 6873.6 6879.1 6907.3 6922.9 5334.3 5335.1 5404.4 5422.5 5422.9 4603.4 4614.2 4664.2 4702.9 4718.4 5768.9 5843.0 5851.6 5852.8 5853.7 6825.9 6827.2 6901.2 6904.3 6925.8 5788.5 5790.2 5838.8 5855.8 5877.3 5669.7 5779.1 5795.1 5816.3 5887.4 6473.8 6522.1 6539.6 6564.6 6575.5 6820.5 6839.0 6854.8 6910.1 6911.7 6573.1 6583.7 6585.7 6689.1 6695.6 6714.8 6715.0 6718.4 6790.8 6825.7 6833.5 6874.4 6935.1 6940.0 6952.9 6506.4 6512.0 6587.0 6587.7 6592.1 6531.2 6566.6 6635.9 6661.6 6665.0 6536.3 6588.1 6624.0 6636.0 6636.9 6587.7 6668.1 6668.5 6715.4 6744.9 6356.3 6404.6 6411.5 6418.6 6534.5 07:33:15 INFO - PID 3808 | 07:33:15 INFO - PID 3808 | [#1] ext.html Cycles:180 Average:37474.85 Median:19532.62 stddev:53712.29 (275.0%) stddev-sans-first:53792.68 07:33:15 INFO - PID 3808 | Values: 879.5 898.0 905.5 906.5 910.4 41196.4 41650.2 41888.0 42050.0 42067.6 38718.2 38932.0 38939.2 39049.4 39114.6 7165.4 7239.5 7256.5 7259.1 7264.4 7085.7 7161.0 7166.0 7177.7 7180.4 7139.5 7157.1 7158.1 7784.1 7907.5 7814.4 7846.5 7864.1 7864.9 7867.0 7781.3 7835.7 7864.3 7866.0 7867.3 38289.4 38488.6 38586.4 39170.4 39518.8 3489.6 3545.6 3546.6 3548.8 3553.4 33081.2 33100.8 33222.8 33346.8 33361.3 5149.4 5159.6 5187.9 5228.5 5259.1 193845.0 195004.0 195306.0 195617.0 196013.0 40654.0 41433.8 45935.3 46647.5 47650.8 49622.0 49823.8 49931.8 49941.5 49983.0 13821.7 13872.2 13983.9 14036.2 14054.0 11079.4 11156.5 11197.8 11303.7 11311.4 13544.8 13565.3 13603.5 13679.9 13773.3 5420.7 5455.5 5531.5 5535.1 5556.8 970.8 1007.2 1016.8 1020.1 1022.4 22676.9 22718.7 23002.8 23095.0 23230.0 1087.5 1103.8 1107.8 1109.4 1111.4 11939.5 12033.5 12175.9 12314.0 12414.6 233160.0 233523.0 234055.0 234064.0 234067.0 52344.2 52563.5 52690.0 52724.5 52790.7 1437.0 1443.5 1449.9 1465.3 1468.8 22688.4 22725.5 22845.1 22935.9 22986.2 36672.6 36702.6 36704.6 36719.6 36746.8 188737.0 189842.0 189914.0 190319.0 190585.0 33346.7 35135.1 35511.8 35627.1 35720.8 44244.1 45321.0 45508.1 45510.0 45775.3 32911.3 33048.0 33338.8 33380.0 33684.7 32684.5 32803.0 33060.7 33123.7 33782.8 36144.0 37719.8 37753.2 37800.4 37866.8 32054.0 32239.8 32303.7 32438.5 32996.0 24043.4 24058.4 24263.5 24666.8 24673.0 07:33:15 INFO - PID 3808 | 07:33:15 INFO - PID 3808 | [#2] jquery.html Cycles:180 Average:34579.33 Median:22709.49 stddev:76805.07 (338.2%) stddev-sans-first:76978.14 07:33:15 INFO - PID 3808 | Values: 591.9 592.9 594.1 597.2 598.7 22086.4 22933.8 22978.8 23001.5 23006.9 22454.2 22635.4 22779.2 22829.0 24041.2 6514.0 6740.0 6740.6 6742.2 6743.7 6439.0 6601.6 6674.1 6685.0 6707.2 6568.2 6668.1 6673.4 6676.8 6684.3 6634.1 6656.5 6931.3 7161.4 7223.7 6961.1 6974.2 7018.8 7020.8 7033.8 30212.4 30807.4 30994.8 31002.8 31132.0 2023.3 2071.5 2074.5 2077.6 2086.0 20207.0 21685.8 21918.9 21968.8 22112.0 2561.8 2584.7 2618.8 2622.7 2642.7 108347.5 113914.0 137603.0 143863.3 144018.6 20060.0 20773.8 21762.3 21952.7 22639.8 44201.0 44420.0 44435.8 44456.8 44678.3 10654.5 10658.7 10796.7 10863.2 10971.2 8704.7 8829.0 8852.2 8891.2 8912.5 11366.2 11374.0 11633.8 11640.0 11701.6 3753.9 3863.8 3952.0 4029.8 4096.8 918.9 932.8 949.0 958.5 962.4 18651.5 18952.0 19153.8 19345.9 19372.3 994.7 1005.5 1007.9 1008.8 1011.7 10527.2 10762.7 10830.9 10832.5 10907.6 435173.0 438257.0 440006.0 477578.0 489691.0 106141.5 107355.0 107860.0 110389.0 112397.0 994.6 1033.5 1050.1 1093.5 1117.5 20094.2 20216.8 20510.6 20651.5 20652.9 54391.2 55697.9 56281.0 56386.4 57569.7 67771.3 67783.7 68081.7 68430.3 68465.0 19353.8 19960.2 20043.1 20045.3 20082.6 23166.7 23191.5 23224.3 23286.2 23528.2 18062.3 18147.6 18217.3 18468.4 18718.6 16382.8 16676.3 17139.1 17246.4 17263.7 18388.4 18504.5 18750.9 18790.9 18940.7 19313.6 19345.7 19385.9 19451.1 19559.5 17825.8 17915.9 17978.6 18148.7 18293.4 07:33:15 INFO - PID 3808 | 07:33:15 INFO - PID 3808 | [#3] mootools.html Cycles:180 Average:9570.65 Median:27725.81 stddev:6911.51 (24.9%) stddev-sans-first:6903.29 07:33:15 INFO - PID 3808 | Values: 1348.5 1391.6 1400.3 1418.7 1424.9 15286.6 15952.0 15956.6 15975.0 15982.0 15604.6 15723.6 15723.8 15799.1 15838.0 2759.7 2812.5 2842.9 2848.9 2853.4 2774.8 2802.6 2808.1 2819.7 2821.2 2709.3 2820.8 2832.7 2834.8 2840.3 2786.6 2793.5 2798.6 2828.7 2832.2 2690.6 2740.6 2741.0 2754.7 2763.0 15188.4 15505.5 15579.5 15647.0 15658.7 8223.8 8505.0 8519.3 8519.7 8554.3 5648.5 5723.7 5773.0 5774.1 5867.7 1866.6 1890.8 1918.8 1924.4 1932.3 27450.0 27522.7 27707.0 27719.6 27732.0 6965.0 7145.4 7202.6 7316.3 7422.3 18043.1 18058.8 18074.5 18083.4 18097.3 7853.6 7960.0 7966.7 7970.7 7973.6 6851.3 7305.3 7324.1 7325.6 7333.5 5237.7 5411.6 5442.5 5449.2 5470.7 1428.4 1456.7 1480.3 1484.1 1497.2 885.3 896.2 910.8 911.8 916.9 8259.4 8359.2 8480.2 8559.7 8639.8 718.8 727.8 728.5 729.1 808.3 5225.3 5343.1 5346.8 5422.9 5545.3 21661.4 22620.0 22665.7 22888.1 22939.3 21077.7 22254.6 22427.3 22511.0 22518.0 11237.4 11432.7 11436.9 11509.5 11516.9 7805.2 8392.1 8579.4 8591.9 8679.2 18800.3 18832.2 18993.3 19293.4 19351.7 19215.8 19456.2 19489.3 19549.9 19588.9 10829.7 11030.0 11032.4 11034.9 11043.7 13944.3 14030.5 14082.6 14137.2 14168.4 11448.5 11589.2 11669.4 11689.8 11920.9 10827.7 10965.3 11068.7 11392.4 11495.9 9829.1 9891.2 9943.3 9945.7 9959.8 7078.6 7292.5 7301.2 7319.3 7332.0 7349.3 7385.9 7396.5 7413.6 7426.7 07:33:15 INFO - PID 3808 | 07:33:15 INFO - PID 3808 | [#4] prototype.html Cycles:180 Average:5483.92 Median:6125.19 stddev:1516.37 (24.8%) stddev-sans-first:1474.09 07:33:15 INFO - PID 3808 | Values: 517.3 519.0 537.8 538.3 549.3 6180.2 6214.7 6256.7 6330.6 6356.5 6143.6 6258.1 6263.2 6277.2 6284.6 5178.4 5196.5 5211.1 5213.7 5215.7 4814.9 5174.5 5187.4 5222.8 5248.2 4864.6 4901.5 4939.5 4942.1 4945.1 4897.4 4916.4 4918.7 4945.4 4974.4 4381.8 4412.2 4439.6 4446.6 4462.5 6041.2 6064.7 6065.4 6099.6 6106.7 5642.9 5647.2 5653.7 5707.0 5737.6 6427.2 6503.5 6515.2 6542.5 6565.0 4693.0 4866.3 4890.0 4904.1 4920.9 6687.3 6706.1 6833.1 6838.8 6849.7 4965.0 5003.1 5003.4 5013.9 5018.9 5042.7 5078.7 5086.5 5097.9 5117.2 5644.1 5653.3 5689.4 5695.9 5714.3 6507.9 6514.3 6546.5 6615.0 6621.4 1760.1 1769.1 1773.6 1776.7 1784.7 1128.5 1187.6 1194.9 1211.6 1216.6 5633.7 5648.7 5649.0 5690.9 5698.9 6624.4 6643.6 6688.7 6720.7 6743.2 5567.7 5593.6 5595.0 5608.2 5610.5 3494.1 3498.9 3509.8 3522.6 3536.5 6270.0 6276.9 6282.3 6294.6 6302.5 6506.4 6682.2 6689.7 6714.0 6747.6 6296.3 6346.6 6365.5 6391.3 6418.5 6014.7 6431.0 6491.7 6518.6 6623.6 6412.6 6489.2 6498.5 6555.3 6584.7 6284.3 6371.5 6418.7 6453.7 6532.7 5428.6 5473.7 5499.1 5573.6 5590.5 6478.3 6564.4 6615.9 6792.0 6895.3 6306.1 6480.7 6481.1 6522.5 6571.0 6420.4 6453.5 6459.0 6468.5 6497.5 6400.0 6417.5 6418.5 6526.8 6533.4 6525.6 6552.6 6569.6 6628.3 6643.4 6251.9 6252.6 6316.4 6332.0 6401.2 07:33:15 INFO - PID 3808 | 07:33:15 INFO - PID 3808 | [#5] yui.html Cycles:180 Average:24309.82 Median:19081.38 stddev:35119.41 (184.1%) stddev-sans-first:35173.94 07:33:15 INFO - PID 3808 | Values: 899.6 904.8 921.3 924.8 925.5 18798.7 19317.9 19375.9 19429.2 19435.2 18352.9 18749.7 18999.4 19163.3 19395.8 958.3 964.9 965.2 966.5 967.0 962.5 995.2 999.1 1002.3 1003.0 1042.2 1044.1 1046.7 1046.7 1048.5 1041.5 1046.0 1047.3 1047.7 1048.4 720.1 731.2 733.7 734.0 772.6 20687.7 20974.8 21043.9 21064.6 21069.4 21418.7 21501.2 22174.8 22421.9 22500.3 17683.9 17797.4 17832.1 17959.6 18059.4 3705.6 3728.8 3742.6 3744.9 3748.6 133996.3 135278.0 135429.3 135785.0 136015.0 16732.2 16906.8 16991.0 17224.0 17329.4 31330.2 32166.7 32206.8 32208.7 32514.0 8456.3 8560.2 8561.0 8562.5 8568.1 4351.2 4388.1 4470.4 4486.4 4496.9 5521.2 5577.4 5577.4 5598.5 5619.2 1005.7 1038.3 1041.7 1050.1 1056.0 639.4 646.4 646.7 646.7 659.5 11170.9 11617.9 11799.0 11935.9 12100.9 689.4 690.5 694.7 706.7 712.3 5460.5 5502.6 5508.2 5536.7 5678.9 117294.4 119173.4 119677.4 120036.0 123574.0 107516.0 112180.4 112450.0 113551.0 114109.1 96313.5 97790.5 98075.0 99626.0 99674.0 10309.0 10496.3 10516.9 10522.5 10748.9 44728.0 45021.1 45692.0 46464.1 46634.8 55929.4 55971.2 57144.7 57681.4 58238.4 15750.2 15941.1 16096.7 16103.7 16248.4 15849.7 15989.1 16185.5 16367.0 16458.1 15036.5 15294.6 15359.5 15489.6 15508.5 15127.6 15220.4 15505.5 15519.3 15574.8 14336.6 14450.1 14462.2 14515.0 14553.4 11547.2 11639.6 11801.4 11983.6 12296.8 11150.6 11374.3 11445.4 11449.1 11462.9 07:33:15 INFO - PID 3808 | -------- Summary: end -------- 07:33:15 INFO - PID 3808 | 07:33:16 INFO - TEST-INFO | 3808: exit 0 07:33:16 INFO - TEST-OK | dromaeo_css | took 1112081ms 07:33:16 INFO - TEST-START | kraken 07:33:16 INFO - Initialising browser for kraken test... 07:33:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50563/getInfo.html -profile /tmp/tmpqxLSFG/profile 07:33:16 INFO - TEST-INFO | started process 4181 (/builds/slave/test/build/application/firefox/firefox http://localhost:50563/getInfo.html) 07:33:21 INFO - TEST-INFO | 4181: exit 0 07:33:22 INFO - Browser initialized. 07:33:22 INFO - Running cycle 1/1 for kraken test... 07:33:22 INFO - Using env: {'DISPLAY': ':0', 07:33:22 INFO - 'HOME': '/home/cltbld', 07:33:22 INFO - 'JSGC_DISABLE_POISONING': '1', 07:33:22 INFO - 'LANG': 'en_US.UTF-8', 07:33:22 INFO - 'LANGUAGE': 'en_US:en', 07:33:22 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:33:22 INFO - 'LOGNAME': 'cltbld', 07:33:22 INFO - 'MAIL': '/var/mail/cltbld', 07:33:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:33:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:33:22 INFO - 'MOZ_CRASHREPORTER': '1', 07:33:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:22 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:33:22 INFO - 'MOZ_NO_REMOTE': '1', 07:33:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:33:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:22 INFO - 'NO_EM_RESTART': '1', 07:33:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:22 INFO - 'PWD': '/builds/slave/test', 07:33:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:33:22 INFO - 'RUST_BACKTRACE': 'full', 07:33:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:33:22 INFO - 'SHELL': '/bin/bash', 07:33:22 INFO - 'SHLVL': '1', 07:33:22 INFO - 'TERM': 'linux', 07:33:22 INFO - 'TMOUT': '86400', 07:33:22 INFO - 'USER': 'cltbld', 07:33:22 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233', 07:33:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:22 INFO - '_': '/tools/buildbot/bin/python'} 07:33:22 INFO - TEST-INFO | started process 4417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqxLSFG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:22 INFO - PID 4417 | 07:33:22 INFO - PID 4417 | (/builds/slave/test/build/application/firefox/firefox:4467): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:33:22 INFO - PID 4417 | 07:33:33 INFO - PID 4417 | RSS: Main: 154587136 07:33:33 INFO - PID 4417 | 07:33:35 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50563/tests/kraken/driver.html?testName=audio-beat-detection) 07:33:36 INFO - PID 4417 | RSS: Main: 160362496 07:33:36 INFO - PID 4417 | 07:33:39 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50563/tests/kraken/driver.html?testName=audio-dft) 07:33:40 INFO - PID 4417 | RSS: Main: 160649216 07:33:40 INFO - PID 4417 | 07:33:42 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50563/tests/kraken/driver.html?testName=audio-fft) 07:33:43 INFO - PID 4417 | RSS: Main: 156680192 07:33:43 INFO - PID 4417 | 07:33:45 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50563/tests/kraken/driver.html?testName=audio-oscillator) 07:33:46 INFO - PID 4417 | RSS: Main: 157155328 07:33:46 INFO - PID 4417 | 07:33:48 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50563/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:33:48 INFO - PID 4417 | RSS: Main: 157413376 07:33:48 INFO - PID 4417 | 07:33:52 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50563/tests/kraken/driver.html?testName=imaging-darkroom) 07:33:52 INFO - PID 4417 | RSS: Main: 159408128 07:33:52 INFO - PID 4417 | 07:33:56 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50563/tests/kraken/driver.html?testName=imaging-desaturate) 07:33:57 INFO - PID 4417 | RSS: Main: 159707136 07:33:57 INFO - PID 4417 | 07:34:00 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50563/tests/kraken/driver.html?testName=json-parse-financial) 07:34:00 INFO - PID 4417 | RSS: Main: 160477184 07:34:00 INFO - PID 4417 | 07:34:02 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50563/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:34:02 INFO - PID 4417 | RSS: Main: 159752192 07:34:02 INFO - PID 4417 | 07:34:04 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50563/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:34:05 INFO - PID 4417 | RSS: Main: 157806592 07:34:05 INFO - PID 4417 | 07:34:07 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50563/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:34:08 INFO - PID 4417 | RSS: Main: 156946432 07:34:08 INFO - PID 4417 | 07:34:10 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50563/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:34:11 INFO - PID 4417 | RSS: Main: 157773824 07:34:11 INFO - PID 4417 | 07:34:14 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50563/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:34:14 INFO - PID 4417 | RSS: Main: 157261824 07:34:14 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | Cycle 1(1): loaded http://localhost:50563/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50563/tests/kraken/driver.html?testName=ai-astar) 07:34:16 INFO - PID 4417 | RSS: Main: 159006720 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | __start_tp_report 07:34:16 INFO - PID 4417 | _x_x_mozilla_page_load 07:34:16 INFO - PID 4417 | _x_x_mozilla_page_load_details 07:34:16 INFO - PID 4417 | |i|pagename|runs| 07:34:16 INFO - PID 4417 | |0;ai-astar;106;107;106;105;107;106;107;107;109;108 07:34:16 INFO - PID 4417 | |1;audio-beat-detection;116;123;116;116;116;117;117;119;141;118 07:34:16 INFO - PID 4417 | |2;audio-dft;164;160;161;161;162;161;161;172;170;164 07:34:16 INFO - PID 4417 | |3;audio-fft;73;74;74;74;73;74;76;79;74;74 07:34:16 INFO - PID 4417 | |4;audio-oscillator;79;79;79;78;79;79;79;78;78;79 07:34:16 INFO - PID 4417 | |5;imaging-gaussian-blur;117;114;111;114;115;114;113;114;115;110 07:34:16 INFO - PID 4417 | |6;imaging-darkroom;148;147;148;149;150;148;148;149;148;147 07:34:16 INFO - PID 4417 | |7;imaging-desaturate;94;95;94;93;94;99;99;94;94;94 07:34:16 INFO - PID 4417 | |8;json-parse-financial;52;51;53;52;52;53;54;53;52;50 07:34:16 INFO - PID 4417 | |9;json-stringify-tinderbox;38;38;38;38;39;38;38;38;39;38 07:34:16 INFO - PID 4417 | |10;stanford-crypto-aes;75;72;74;75;80;72;72;72;72;76 07:34:16 INFO - PID 4417 | |11;stanford-crypto-ccm;120;112;116;111;111;117;114;117;110;116 07:34:16 INFO - PID 4417 | |12;stanford-crypto-pbkdf2;167;172;165;170;169;172;167;175;176;169 07:34:16 INFO - PID 4417 | |13;stanford-crypto-sha256-iterative;66;67;64;62;64;66;63;65;65;66 07:34:16 INFO - PID 4417 | __end_tp_report 07:34:16 INFO - PID 4417 | __start_cc_report 07:34:16 INFO - PID 4417 | _x_x_mozilla_cycle_collect,777 07:34:16 INFO - PID 4417 | __end_cc_report 07:34:16 INFO - PID 4417 | __startTimestamp1506004456657__endTimestamp 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | ------- Summary: start ------- 07:34:16 INFO - PID 4417 | Number of tests: 14 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#0] ai-astar Cycles:10 Average:106.80 Median:107.00 stddev:1.14 (1.1%) stddev-sans-first:1.17 07:34:16 INFO - PID 4417 | Values: 106.0 107.0 106.0 105.0 107.0 106.0 107.0 107.0 109.0 108.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#1] audio-beat-detection Cycles:10 Average:119.90 Median:117.50 stddev:7.72 (6.6%) stddev-sans-first:8.06 07:34:16 INFO - PID 4417 | Values: 116.0 123.0 116.0 116.0 116.0 117.0 117.0 119.0 141.0 118.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.00 stddev:4.14 (2.5%) stddev-sans-first:4.39 07:34:16 INFO - PID 4417 | Values: 164.0 160.0 161.0 161.0 162.0 161.0 161.0 172.0 170.0 164.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#3] audio-fft Cycles:10 Average:74.50 Median:74.00 stddev:1.78 (2.4%) stddev-sans-first:1.80 07:34:16 INFO - PID 4417 | Values: 73.0 74.0 74.0 74.0 73.0 74.0 76.0 79.0 74.0 74.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:79.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 07:34:16 INFO - PID 4417 | Values: 79.0 79.0 79.0 78.0 79.0 79.0 79.0 78.0 78.0 79.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:2.00 (1.8%) stddev-sans-first:1.73 07:34:16 INFO - PID 4417 | Values: 117.0 114.0 111.0 114.0 115.0 114.0 113.0 114.0 115.0 110.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 07:34:16 INFO - PID 4417 | Values: 148.0 147.0 148.0 149.0 150.0 148.0 148.0 149.0 148.0 147.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:94.00 stddev:2.16 (2.3%) stddev-sans-first:2.26 07:34:16 INFO - PID 4417 | Values: 94.0 95.0 94.0 93.0 94.0 99.0 99.0 94.0 94.0 94.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.50 stddev:1.14 (2.2%) stddev-sans-first:1.20 07:34:16 INFO - PID 4417 | Values: 52.0 51.0 53.0 52.0 52.0 53.0 54.0 53.0 52.0 50.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 07:34:16 INFO - PID 4417 | Values: 38.0 38.0 38.0 38.0 39.0 38.0 38.0 38.0 39.0 38.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:74.50 stddev:2.62 (3.5%) stddev-sans-first:2.76 07:34:16 INFO - PID 4417 | Values: 75.0 72.0 74.0 75.0 80.0 72.0 72.0 72.0 72.0 76.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#11] stanford-crypto-ccm Cycles:10 Average:114.40 Median:116.00 stddev:3.31 (2.9%) stddev-sans-first:2.82 07:34:16 INFO - PID 4417 | Values: 120.0 112.0 116.0 111.0 111.0 117.0 114.0 117.0 110.0 116.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:171.00 stddev:3.55 (2.1%) stddev-sans-first:3.57 07:34:16 INFO - PID 4417 | Values: 167.0 172.0 165.0 170.0 169.0 172.0 167.0 175.0 176.0 169.0 07:34:16 INFO - PID 4417 | 07:34:16 INFO - PID 4417 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.50 stddev:1.55 (2.4%) stddev-sans-first:1.58 07:34:16 INFO - PID 4417 | Values: 66.0 67.0 64.0 62.0 64.0 66.0 63.0 65.0 65.0 66.0 07:34:16 INFO - PID 4417 | -------- Summary: end -------- 07:34:16 INFO - PID 4417 | 07:34:17 INFO - PID 4417 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 07:34:17 INFO - TEST-INFO | 4417: exit 0 07:34:17 INFO - TEST-OK | kraken | took 60845ms 07:34:17 INFO - SUITE-END | took 1172s 07:34:17 INFO - Completed test suite (00:19:32) 07:34:17 INFO - javascript benchmark 07:34:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8616.826199948808, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3128.7340576051033, 3178.7457003439727, 3202.08740508955, 3209.9518507222383, 3210.919727006826, 6247.6251424914535, 6341.7146228419715, 6389.169407976962, 6422.518311126667, 6513.869722605551, 6278.686065696708, 6283.7486500540035, 6330.620162790225, 6365.077063825743, 6370.331115232903, 5764.365919748824, 5810.854728631797, 5832.329282132567, 5836.270466191726, 5901.114832775075, 5903.645781253139, 5913.14259432382, 5925.377835327293, 5966.224390829186, 5978.40215978403, 5893.911591326112, 5902.321233058189, 5936.762529498837, 5974.163617093589, 5990.670513121777, 5338.466153384669, 5639.351474580435, 5758.62568933018, 5820.068788993741, 5874.295084589834, 5772.538196944234, 5776.855578610526, 5778.816276324022, 5855.443732845373, 5862.794802181903, 6274.937250627527, 6339.714712837934, 6347.492200623939, 6394.944834102367, 6414.294427612959, 6756.2568117507035, 6778.728850845961, 6789.321067893221, 6839.0, 6841.931580684229, 6885.070515480396, 6893.827654308632, 6918.100646915919, 6924.826879328007, 6928.376446119873, 6205.7517699291975, 6251.374862513758, 6334.144890439778, 6351.618902865843, 6412.166418365629, 7013.05323781283, 7032.0, 7099.219085900547, 7106.2183159852375, 7128.108986376703, 6699.9665001674675, 6711.261761206263, 6731.0, 6771.3905748482875, 6781.42357899584, 6598.73605055802, 6687.4984376171005, 6707.966460167668, 6712.093867327946, 6739.191297044386, 6534.183227096613, 6574.408303252731, 6755.864882702318, 6797.320267973163, 6831.04365388846, 6829.624370659562, 6873.5875847449315, 6879.140107486564, 6907.309269073153, 6922.861542769117, 5334.279872217201, 5335.146376579729, 5404.351477822686, 5422.48486393792, 5422.91865622016, 4603.424571928509, 4614.192516309699, 4664.183767840615, 4702.929456058162, 4718.386609740712, 5768.884622307531, 5843.035899076604, 5851.590388672837, 5852.824415267549, 5853.678047707417, 6825.897611535831, 6827.180738311435, 6901.240863505011, 6904.344087311697, 6925.792226233221, 5788.5369170466265, 5790.189373487715, 5838.795642152504, 5855.824325270249, 5877.265341832271, 5669.688167150846, 5779.075347944308, 5795.13073039039, 5816.331121920949, 5887.4406931341455, 6473.805785826433, 6522.119513865662, 6539.574927629689, 6564.606123632598, 6575.506836987245, 6820.522563420513, 6839.0, 6854.760083397057, 6910.136232970879, 6911.723531058902, 6573.1454910861285, 6583.670816459254, 6585.670716464062, 6689.130413046273, 6695.631740254333, 6714.798556043326, 6715.026321183499, 6718.428933540704, 6790.83022924411, 6825.69284382194, 6833.521653484208, 6874.381305682513, 6935.133108361455, 6940.0, 6952.930470695228, 6506.41442270198, 6512.0, 6586.967065164644, 6587.703553340016, 6592.110065141336, 6531.150950376421, 6566.606003639797, 6635.900461493178, 6661.600303981777, 6665.0, 6536.346365363425, 6588.143541339595, 6624.0, 6636.0377745225705, 6636.867262654817, 6587.670616469061, 6668.099806526056, 6668.499862510428, 6715.3620406061345, 6744.898826517704, 6356.269029061717, 6404.583702059351, 6411.4870810336, 6418.614883107029, 6534.477241820738], "value": 6198.981494677027, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [879.4547380624014, 898.043583583484, 905.5155491811882, 906.5286051253348, 910.3627460777453, 41196.38205426921, 41650.166996660046, 41888.0, 42049.99999999992, 42067.57932420675, 38718.22563548727, 38932.0, 38939.22121557567, 39049.41425878607, 39114.60885391145, 7165.390941769942, 7239.529430586995, 7256.455765817558, 7259.128904531437, 7264.38252748517, 7085.7165713371405, 7161.0, 7166.0, 7177.712891484334, 7180.353768160864, 7139.464540159483, 7157.069580954469, 7158.105236845395, 7784.0659120905575, 7907.5255484670815, 7814.413918956073, 7846.489978151402, 7864.05631324239, 7864.882026769575, 7867.0, 7781.3385862201785, 7835.686572537149, 7864.252895974873, 7866.0, 7867.331276841418, 38289.425658615146, 38488.615113848784, 38586.421203681966, 39170.412443813366, 39518.802405987786, 3489.616142224353, 3545.556805399325, 3546.6098729139776, 3548.8048157351336, 3553.4136867416846, 33081.17297067593, 33100.83449582737, 33222.83388583066, 33346.83326583376, 33361.332773344395, 5149.382074151088, 5159.638825282232, 5187.922181167248, 5228.503292187236, 5259.105951988133, 193845.0, 195003.99999999857, 195306.0, 195617.0, 196013.0, 40654.0, 41433.79283103565, 45935.31097033547, 46647.533524665, 47650.76174619105, 49622.0, 49823.75088124609, 49931.75034124806, 49941.500584993686, 49983.0, 13821.723565528633, 13872.237026963614, 13983.930080349737, 14036.157830530201, 14054.0, 11079.390633515233, 11156.497957591928, 11197.832032519518, 11303.660890173143, 11311.377874216832, 13544.796828047587, 13565.321733913264, 13603.455861765617, 13679.863201368058, 13773.311334433438, 5420.71763306983, 5455.454454554513, 5531.502164805106, 5535.142052981794, 5556.77772889088, 970.7621632699893, 1007.2143727892255, 1016.8271393863062, 1020.1481762728057, 1022.4018948914819, 22676.886615567146, 22718.659220111713, 23002.769972300397, 23095.0, 23230.0, 1087.478010554938, 1103.7957977773976, 1107.8449017137607, 1109.362116782837, 1111.4220605285368, 11939.462724177432, 12033.518659253707, 12175.939120304341, 12314.014878809852, 12414.565490207799, 233160.0, 233523.0, 234055.0, 234064.0, 234067.0, 52344.214836776715, 52563.474365256625, 52690.0, 52724.47275527272, 52790.73604631952, 1436.9856301436853, 1443.5092068696483, 1449.8550144985418, 1465.340596731475, 1468.7943687883492, 22688.432789179744, 22725.545489090455, 22845.08619655195, 22935.885320573958, 22986.195483158008, 36672.63327366799, 36702.63297366992, 36704.63295367012, 36719.63280367162, 36746.816265918496, 188737.0, 189842.0, 189914.0, 190319.0, 190585.0, 33346.66653333532, 35135.12162195965, 35511.82244088763, 35627.10932226715, 35720.82139589285, 44244.11511769811, 45321.0, 45508.08983820371, 45510.0, 45775.313370298805, 32911.34177316488, 33048.0, 33338.833305833316, 33380.0, 33684.66315336815, 32684.50973235356, 32803.0, 33060.66939330672, 33123.66876331302, 33782.83108584442, 36144.0, 37719.81140094282, 37753.24493510169, 37800.43299350567, 37866.8106659465, 32054.0, 32239.838800805846, 32303.676963231006, 32438.513422299155, 32996.0, 24043.398915027265, 24058.39854003664, 24263.51472970566, 24666.753332466447, 24673.0], "value": 16983.884377296137, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.8520369907524, 592.9288485381754, 594.1355327997763, 597.2086984745213, 598.7156100852096, 22086.447838804037, 22933.770662293395, 22978.770212297874, 23001.539969200607, 23006.88496557519, 22454.214102506416, 22635.434114147156, 22779.202727904525, 22828.97269622867, 24041.15855945042, 6513.967430162843, 6740.022696708974, 6740.629265390402, 6742.22464416591, 6743.730250789962, 6439.0, 6601.603903765766, 6674.132362792854, 6685.030670552767, 6707.1616047994, 6568.244651865025, 6668.133142691443, 6673.399394054534, 6676.799696009128, 6684.3315668433015, 6634.104395906563, 6656.534042617019, 6931.3415225553745, 7161.427085833121, 7223.674934627902, 6961.095057642525, 6974.163100427981, 7018.75434359795, 7020.7893763187185, 7033.788986330367, 30212.395752084834, 30807.38385232305, 30994.845025774954, 31002.844985774926, 31132.0, 2023.3221870673342, 2071.461420030788, 2074.481379655086, 2077.5948690005457, 2085.968710469329, 20207.0, 21685.783142168533, 21918.89040554803, 21968.780312196992, 22112.0, 2561.8078644101765, 2584.6510721052605, 2618.751218634227, 2622.7246139155304, 2642.656454660901, 108347.45826270818, 113914.0, 137603.0, 143863.28068359592, 144018.55981440155, 20060.0, 20773.792262077488, 21762.347129586138, 21952.670709939364, 22639.773602264097, 44201.0, 44420.0, 44435.77782111133, 44456.77771611186, 44678.32982505265, 10654.467276636136, 10658.733531661615, 10796.650418697664, 10863.18526110545, 10971.23201375912, 8704.651813927372, 8829.028806831244, 8852.20330170275, 8891.155340242667, 8912.509811960275, 11366.204365694488, 11374.0, 11633.825492617618, 11640.0, 11701.590444334408, 3753.868614598476, 3863.7874916879277, 3952.0457238447316, 4029.7582145071387, 4096.815643296059, 918.9264858811279, 932.7668082979255, 949.027247071757, 958.4632605740808, 962.4332542087838, 18651.533711657317, 18952.0, 19153.80846191548, 19345.90327048356, 19372.321968731027, 994.6966175316453, 1005.5073014222978, 1007.9244056695777, 1008.7831116310028, 1011.676263595657, 10527.21045921559, 10762.730931726614, 10830.891691082988, 10832.458377081115, 10907.563697452013, 435173.0, 438257.0, 440006.0, 477578.0, 489691.0, 106141.46929265304, 107355.0, 107860.0, 110389.0, 112397.0, 994.5574219472265, 1033.4987531047436, 1050.1231471721198, 1093.5407129005837, 1117.4747868501954, 20094.19623215055, 20216.79783202149, 20510.589788203855, 20651.4837129073, 20652.896735516228, 54391.18413223726, 55697.88604227974, 56281.0, 56386.43613563812, 57569.71215143897, 67771.3222867765, 67783.66108169427, 68081.65959170173, 68430.3156968424, 68465.0, 19353.8064619352, 19960.20159193616, 20043.098060587017, 20045.298414555422, 20082.598348032665, 23166.652500212847, 23191.536169276857, 23224.3032709019, 23286.184983525494, 23528.176513821934, 18062.277508900024, 18147.637047259246, 18217.271309147487, 18468.44594662162, 18718.625627487647, 16382.75425868589, 16676.332946682483, 17139.057351845768, 17246.396376126777, 17263.654726905643, 18388.44834654909, 18504.537386565444, 18750.906245468686, 18790.906045469685, 18940.715889261948, 19313.61372772565, 19345.70981435308, 19385.90307048512, 19451.124699388278, 19559.511012224808, 17825.821741782416, 17915.910420448337, 17978.5505362367, 18148.72776908374, 18293.359732409303], "value": 12759.042904446696, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.467355394619, 1391.5686137297446, 1400.2858542143504, 1418.7233489469554, 1424.8788852947498, 15286.617834554141, 15952.0, 15956.601084972881, 15975.0, 15982.0, 15604.609884752887, 15723.60690982726, 15723.842761572381, 15799.131047792367, 15838.049717016987, 2759.7240275972345, 2812.5078111330486, 2842.9147125586164, 2848.9287767805763, 2853.443578502193, 2774.8473833939124, 2802.579613058037, 2808.101407549585, 2819.6757372902175, 2821.153653903831, 2709.336212627909, 2820.830750154997, 2832.6884042755282, 2834.8015638905285, 2840.2757296889263, 2786.637737094165, 2793.4552762211174, 2798.594203840442, 2828.7454129128478, 2832.22113918673, 2690.5829596412586, 2740.630014947996, 2740.9725902740915, 2754.724527547229, 2762.958555621647, 15188.392464301414, 15505.457308994244, 15579.532614021597, 15647.0, 15658.686826263525, 8223.794405139859, 8505.0, 8519.275861551776, 8519.701810436667, 8554.315654747605, 5648.491635752761, 5723.713814309268, 5772.9711351442975, 5774.133879918004, 5867.677277749722, 1866.5893503429359, 1890.8203720646516, 1918.7985261547615, 1924.393815947972, 1932.3043704266454, 27450.0, 27522.724772752215, 27707.0, 27719.584206236923, 27732.029378971638, 6965.0, 7145.392641625416, 7202.639868006579, 7316.304951029643, 7422.331990120915, 18043.09784510769, 18058.819411805976, 18074.548136296435, 18083.367082152326, 18097.276108955757, 7853.568053756984, 7960.0, 7966.681332746625, 7970.7210247642215, 7973.601319934096, 6851.3491218334175, 7305.305995930378, 7324.08448943882, 7325.633718314063, 7333.48665593414, 5237.73811309433, 5411.621186516906, 5442.455754424526, 5449.209864569694, 5470.7264636768, 1428.371516532743, 1456.6940942402002, 1480.3412481445653, 1484.0724547158027, 1497.1840347010905, 885.2829208341135, 896.1844721303582, 910.7814124610046, 911.7720569857536, 916.9037251088673, 8259.38054645904, 8359.15788631868, 8480.15198480147, 8559.700410485602, 8639.78400539979, 718.7879575525233, 727.7925791149497, 728.4791374167496, 729.0813574895669, 808.3452403553141, 5225.268462415265, 5343.145096784574, 5346.786128554814, 5422.91865622016, 5545.279113715272, 21661.35015949524, 22620.0, 22665.660015099787, 22888.084476621083, 22939.311820645406, 21077.683834742493, 22254.554908902057, 22427.32718018462, 22510.98700558479, 22518.0, 11237.38194399316, 11432.714182145346, 11436.94281528587, 11509.539618415338, 11516.884831151749, 7805.2194780521495, 8392.118827523022, 8579.442336248125, 8591.914080859111, 8679.17547832955, 18800.34198803035, 18832.24671013144, 18993.33523326677, 19293.421197364103, 19351.709724353863, 19215.80784192196, 19456.221751130364, 19489.317873874345, 19549.902250488656, 19588.90205548963, 10829.67510974672, 11030.0, 11032.393218373067, 11034.944825275823, 11043.723906902393, 13944.30278486092, 14030.508932187324, 14082.577522674335, 14137.151770893779, 14168.362423691166, 11448.484818183368, 11589.188756786943, 11669.35818529989, 11689.766204675689, 11920.940395297968, 10827.66216907254, 10965.342079474938, 11068.667939961813, 11392.43037848121, 11495.942520287344, 9829.115379615869, 9891.208703303575, 9943.25425593069, 9945.701628951143, 9959.800803984024, 7078.646067696697, 7292.48952573336, 7301.233370496234, 7319.304666056929, 7332.0, 7349.265073492608, 7385.852282954418, 7396.519226250277, 7413.592252425952, 7426.702931882664], "value": 6776.118319136083, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.2913109040613, 518.9636725429222, 537.8413368056423, 538.3459097196906, 549.3133583021223, 6180.196574445322, 6214.720337584814, 6256.718447669854, 6330.620162790236, 6356.459700925427, 6143.631382117076, 6258.12386265922, 6263.248410190772, 6277.2467303923595, 6284.5600807943465, 5178.430372659004, 5196.480351964792, 5211.140161873286, 5213.713245771481, 5215.713135777531, 4814.9037019259595, 5174.508421699932, 5187.377514698242, 5222.791088356461, 5248.160294352904, 4864.586510146642, 4901.53435423636, 4939.53074457926, 4942.061008408409, 4945.134601444751, 4897.363342765453, 4916.385451818523, 4918.655694101414, 4945.381827271609, 4974.37820272466, 4381.802818873159, 4412.205802955466, 4439.622632076277, 4446.622037126848, 4462.531434199394, 6041.154238406627, 6064.727087281083, 6065.423784740487, 6099.603525770811, 6106.740506012804, 5642.943570564283, 5647.152927060933, 5653.689047102408, 5706.9714651426475, 5737.598368114233, 6427.2287325520765, 6503.544751867371, 6515.165902832283, 6542.476601871839, 6564.997675708907, 4693.0, 4866.343043689128, 4890.039068354608, 4904.117258893398, 4920.901581968376, 6687.331266873322, 6706.094677218562, 6833.086735714008, 6838.794836154923, 6849.698557274151, 4964.950350496521, 5003.149464591028, 5003.449620541737, 5013.924791128136, 5018.949810501848, 5042.672226305279, 5078.720670363092, 5086.54221120101, 5097.949020509747, 5117.181250999822, 5644.121498036031, 5653.321601407775, 5689.374168841424, 5695.943040569541, 5714.285714285715, 6507.8698426031215, 6514.250861150934, 6546.476281897437, 6615.048560674612, 6621.370969757865, 1760.0935518208132, 1769.080078359246, 1773.6364045370667, 1776.7385156539003, 1784.6698360803293, 1128.4527004402858, 1187.5724739093919, 1194.9163558550817, 1211.6486218996567, 1216.6410908781932, 5633.746481408347, 5648.6610803351105, 5648.9717551412805, 5690.9146362804595, 5698.860227954426, 6624.443255834875, 6643.634600097041, 6688.665566721645, 6720.6651709647, 6743.15710536183, 5567.693776842312, 5593.552515798727, 5594.9720251398485, 5608.158776183605, 5610.49505544503, 3494.0559108861185, 3498.9125271867897, 3509.78941263525, 3522.6125126236093, 3536.504889315498, 6269.962262264782, 6276.937230627726, 6282.277538083087, 6294.590851594632, 6302.4958003359625, 6506.4469520091325, 6682.164729408824, 6689.665516724144, 6714.0, 6747.628880411625, 6296.2759282681245, 6346.587471814318, 6365.490760739037, 6391.328910464427, 6418.486521078303, 6014.699265036818, 6430.967845160744, 6491.675416229265, 6518.57629254097, 6623.595309119381, 6412.647304398303, 6489.221293444817, 6498.512611554058, 6555.311692272243, 6584.703688333941, 6284.277308109443, 6371.534547054296, 6418.711157997995, 6453.708608609737, 6532.673366331759, 5428.59285553593, 5473.726313684379, 5499.1476321170285, 5573.554115670818, 5590.5248053915875, 6478.255000674794, 6564.409203171737, 6615.933840661532, 6792.0, 6895.275996020346, 6306.108167549625, 6480.70836812354, 6481.125048118443, 6522.478201743944, 6571.0, 6420.394901274682, 6453.483721302378, 6459.0, 6468.5472016958365, 6497.505573718166, 6399.968000159969, 6417.454516366161, 6418.4544313731985, 6526.836829079311, 6533.379328963741, 6525.608463492205, 6552.6068435894, 6569.638669873012, 6628.337166283333, 6643.435307998874, 6251.874662560601, 6252.624842509464, 6316.431521163117, 6331.968340158454, 6401.167848179707], "value": 5039.77996491593, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [899.6266549382007, 904.8461761500545, 921.2952091649887, 924.8381533231687, 925.4863550729342, 18798.718019229724, 19317.903410482926, 19375.903120484396, 19429.222831086776, 19435.22259109634, 18352.90823545884, 18749.718754218662, 18999.43001709951, 19163.329283475083, 19395.806041939577, 958.3100167879134, 964.864918911353, 965.2374624046995, 966.5119114847028, 967.0281367225928, 962.4610218277752, 995.1590905684722, 999.1008092716577, 1002.3284399452384, 1003.032074048543, 1042.2235434601232, 1044.0655613226158, 1046.7383154211448, 1046.7435478307825, 1048.4652827058217, 1041.536516250269, 1045.9529321180564, 1047.261680515235, 1047.6804574604741, 1048.3709774135534, 720.1286443403488, 731.2322061835052, 733.7175187552778, 733.9559626422432, 772.6214155064034, 20687.689684654742, 20974.790252097435, 21043.894780526152, 21064.5787084259, 21069.367918962456, 21418.678719819214, 21501.24745633895, 22174.77825221743, 22421.887890560607, 22500.32499025032, 17683.911580442014, 17797.377091801725, 17832.10839458022, 17959.551011224692, 18059.367922122663, 3705.6294370562728, 3728.7949162796035, 3742.644448777362, 3744.8876533704033, 3748.5876553579083, 133996.33001834928, 135278.0, 135429.32285338608, 135785.0, 136015.0, 16732.24704888283, 16906.830931690525, 16991.0, 17224.0, 17329.393471228446, 31330.21674458111, 32166.678333216838, 32206.83896580549, 32208.677913221036, 32514.0, 8456.323494120456, 8560.186782255676, 8561.0, 8562.48625082497, 8568.10034946334, 4351.19502891966, 4388.144311859221, 4470.396496472935, 4486.371907932892, 4496.865094047183, 5521.199426083195, 5577.358603760538, 5577.414371491016, 5598.468145526169, 5619.241402410704, 1005.7234260578399, 1038.2991480750463, 1041.739565108723, 1050.1179009631946, 1056.028453822485, 639.3670266436195, 646.4408286831857, 646.7251418147363, 646.747768370336, 659.5416185750953, 11170.93876081771, 11617.88382116168, 11799.0, 11935.896666866678, 12100.878991210151, 689.407109885498, 690.5442408010689, 694.7082225465317, 706.6749295324106, 712.344642928507, 5460.453954604508, 5502.58730595207, 5508.20131080991, 5536.674596669328, 5678.858028549237, 117294.41352793181, 119173.40413298052, 119677.40161299138, 120036.0, 123574.0, 107516.0, 112180.43909780562, 112450.0, 113551.0, 114109.14727131889, 96313.51843240739, 97790.5110474443, 98075.01924980801, 99626.0, 99674.0, 10309.0, 10496.317739346918, 10516.947415262875, 10522.473876306001, 10748.946255268673, 44728.0, 45021.099578008914, 45692.0, 46464.07071858612, 46634.76682616565, 55929.440705592424, 55971.22661538143, 57144.71427642835, 57681.42318576929, 58238.4176158233, 15750.212489375257, 15941.12323822201, 16096.67806643884, 16103.67792644117, 16248.431304904745, 15849.68300634004, 15989.120598366737, 16185.514434566981, 16367.0, 16458.09480478585, 15036.473723419627, 15294.617634559225, 15359.462418815288, 15489.612759681098, 15508.5347439577, 15127.621809454851, 15220.391184352502, 15505.534833955, 15519.301631426839, 15574.766378504559, 14336.641583960485, 14450.132992020512, 14462.20457874827, 14515.0, 14553.417863285349, 11547.191696581158, 11639.59261425846, 11801.409929503663, 11983.64049078529, 12296.75406491883, 11150.553977840795, 11374.260673056226, 11445.370504622326, 11449.14131440162, 11462.94268528652], "value": 8922.566330104126, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1414.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 106.0, 105.0, 107.0, 106.0, 107.0, 107.0, 109.0, 108.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 123.0, 116.0, 116.0, 116.0, 117.0, 117.0, 119.0, 141.0, 118.0], "value": 119.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 161.0, 161.0, 162.0, 161.0, 161.0, 172.0, 170.0, 164.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 74.0, 73.0, 74.0, 76.0, 79.0, 74.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 78.0, 79.0, 79.0, 79.0, 78.0, 78.0, 79.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 111.0, 114.0, 115.0, 114.0, 113.0, 114.0, 115.0, 110.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 149.0, 150.0, 148.0, 148.0, 149.0, 148.0, 147.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 93.0, 94.0, 99.0, 99.0, 94.0, 94.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 52.0, 52.0, 53.0, 54.0, 53.0, 52.0, 50.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0, 38.0, 39.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 72.0, 74.0, 75.0, 80.0, 72.0, 72.0, 72.0, 72.0, 76.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 112.0, 116.0, 111.0, 111.0, 117.0, 114.0, 117.0, 110.0, 116.0], "value": 114.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 172.0, 165.0, 170.0, 169.0, 172.0, 167.0, 175.0, 176.0, 169.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 64.0, 62.0, 64.0, 66.0, 63.0, 65.0, 65.0, 66.0], "value": 64.8, "unit": "score"}]}]} 07:34:17 INFO - Return code: 0 07:34:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:34:17 INFO - # TBPL SUCCESS # 07:34:17 INFO - Running post-action listener: _package_coverage_data 07:34:17 INFO - Running post-action listener: _resource_record_post_action 07:34:17 INFO - [mozharness: 2017-09-21 14:34:17.435377Z] Finished run-tests step (success) 07:34:17 INFO - Running post-run listener: _resource_record_post_run 07:34:17 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 11100160; Write bytes: 338440192; Read time: 3204; Write time: 353976 07:34:17 INFO - TinderboxPrint: CPU usage
13.1% 07:34:17 INFO - TinderboxPrint: I/O read bytes / time
11,100,160 / 3,204 07:34:17 INFO - TinderboxPrint: I/O write bytes / time
338,440,192 / 353,976 07:34:17 INFO - TinderboxPrint: CPU idle
8,248.1 (86.9%) 07:34:17 INFO - TinderboxPrint: CPU user
1,192.2 (12.6%) 07:34:17 INFO - TinderboxPrint: Swap in / out
0 / 0 07:34:17 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13148160; Read time: 0; Write time: 2568 07:34:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:34:17 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8863744; Write bytes: 282460160; Read time: 2424; Write time: 318308 07:34:18 INFO - Running post-run listener: _upload_blobber_files 07:34:18 INFO - Blob upload gear active. 07:34:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:34:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:34:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:34:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:34:18 INFO - (blobuploader) - INFO - Open directory for files ... 07:34:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:34:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:34:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:19 INFO - (blobuploader) - INFO - Done attempting. 07:34:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:34:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:34:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:20 INFO - (blobuploader) - INFO - Done attempting. 07:34:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:34:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:34:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:21 INFO - (blobuploader) - INFO - Done attempting. 07:34:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:34:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:34:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:24 INFO - (blobuploader) - INFO - Done attempting. 07:34:24 INFO - (blobuploader) - INFO - Iteration through files over. 07:34:24 INFO - Return code: 0 07:34:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:34:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:34:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14ce4f0a8cc98f1c6413efe5ab4c60ad9f23e4008803146c4050c2bab9aa292de08f3fc2de16d244933057d6a14a49e3b3a9649349ca55ed6b7d0c372c97d29a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef62d8ac5c4b588ac5d90021598203e4fe7b63de466537a01f60e03516ac06312d72ccf31448bde0c084b0a258fea30d0e28ca99f3889ddfe4e39cd48f1ce51f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c588963960df558524766aa086553f13bb290adf9d10cd6986f0aa3723ccf34438e48a90669767204cbaf405244c47b740cbe00a8220379e7a9ca773f7ab0cd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/166abfda1d222c4e3da28fe734e0f729ff433432fd2b8853eb2123c7bd2998e80e23dfbb156da267a4fc564b18927c2a77722697f0b1ca715a12b3fcfe37d92f"} 07:34:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:34:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:34:24 INFO - Contents: 07:34:24 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14ce4f0a8cc98f1c6413efe5ab4c60ad9f23e4008803146c4050c2bab9aa292de08f3fc2de16d244933057d6a14a49e3b3a9649349ca55ed6b7d0c372c97d29a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef62d8ac5c4b588ac5d90021598203e4fe7b63de466537a01f60e03516ac06312d72ccf31448bde0c084b0a258fea30d0e28ca99f3889ddfe4e39cd48f1ce51f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c588963960df558524766aa086553f13bb290adf9d10cd6986f0aa3723ccf34438e48a90669767204cbaf405244c47b740cbe00a8220379e7a9ca773f7ab0cd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/166abfda1d222c4e3da28fe734e0f729ff433432fd2b8853eb2123c7bd2998e80e23dfbb156da267a4fc564b18927c2a77722697f0b1ca715a12b3fcfe37d92f"} 07:34:24 INFO - Running post-run listener: copy_logs_to_upload_dir 07:34:24 INFO - Copying logs to upload dir... 07:34:24 INFO - mkdir: /builds/slave/test/build/upload/logs 07:34:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.052952 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-21 07:34:23.939034) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 07:34:23.946029) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233 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-Non-PGO/sha512/14ce4f0a8cc98f1c6413efe5ab4c60ad9f23e4008803146c4050c2bab9aa292de08f3fc2de16d244933057d6a14a49e3b3a9649349ca55ed6b7d0c372c97d29a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef62d8ac5c4b588ac5d90021598203e4fe7b63de466537a01f60e03516ac06312d72ccf31448bde0c084b0a258fea30d0e28ca99f3889ddfe4e39cd48f1ce51f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c588963960df558524766aa086553f13bb290adf9d10cd6986f0aa3723ccf34438e48a90669767204cbaf405244c47b740cbe00a8220379e7a9ca773f7ab0cd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/166abfda1d222c4e3da28fe734e0f729ff433432fd2b8853eb2123c7bd2998e80e23dfbb156da267a4fc564b18927c2a77722697f0b1ca715a12b3fcfe37d92f"} build_url:https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14ce4f0a8cc98f1c6413efe5ab4c60ad9f23e4008803146c4050c2bab9aa292de08f3fc2de16d244933057d6a14a49e3b3a9649349ca55ed6b7d0c372c97d29a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef62d8ac5c4b588ac5d90021598203e4fe7b63de466537a01f60e03516ac06312d72ccf31448bde0c084b0a258fea30d0e28ca99f3889ddfe4e39cd48f1ce51f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c588963960df558524766aa086553f13bb290adf9d10cd6986f0aa3723ccf34438e48a90669767204cbaf405244c47b740cbe00a8220379e7a9ca773f7ab0cd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/166abfda1d222c4e3da28fe734e0f729ff433432fd2b8853eb2123c7bd2998e80e23dfbb156da267a4fc564b18927c2a77722697f0b1ca715a12b3fcfe37d92f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 07:34:23.976894) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:34:23.977184) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1506002921.585464-1000704233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:34:24.057293) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 07:34:24.057591) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 07:34:24.060900) ========= ========= Total master_lag: 0.23 =========