builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-071 starttime: 1487369381.73 results: success (0) revision: 0930fdc4cf8e0116e19577005f57f7ec12336ced ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:41.726876) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:41.727412) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:41.727744) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:41.745839) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:41.746496) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 14:09:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2017-02-17 14:09:42 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129753 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:41.895361) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:41.895743) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017526 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:41.945627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:41.946037) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0930fdc4cf8e0116e19577005f57f7ec12336ced --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 0930fdc4cf8e0116e19577005f57f7ec12336ced --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=dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 14:09:42,597 truncating revision to first 12 chars 2017-02-17 14:09:42,597 Setting DEBUG logging. 2017-02-17 14:09:42,597 attempt 1/10 2017-02-17 14:09:42,597 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0930fdc4cf8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 14:09:43,117 unpacking tar archive at: mozilla-central-0930fdc4cf8e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.795483 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:42.754843) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:42.755218) ========= script_repo_revision: 0930fdc4cf8e0116e19577005f57f7ec12336ced ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:42.755690) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:42.756024) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 14:09:42.764550) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-17 14:09:42.764864) ========= /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=dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:09:43 INFO - MultiFileLogger online at 20170217 14:09:43 in /builds/slave/test 14:09: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 14:09:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:09:43 INFO - {'append_to_log': False, 14:09:43 INFO - 'base_work_dir': '/builds/slave/test', 14:09:43 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:09:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:09:43 INFO - 'branch': 'Firefox-Non-PGO', 14:09:43 INFO - 'buildbot_json_path': 'buildprops.json', 14:09:43 INFO - 'config_files': ('talos/linux_config.py',), 14:09:43 INFO - 'default_actions': ('clobber', 14:09:43 INFO - 'read-buildbot-config', 14:09:43 INFO - 'download-and-extract', 14:09:43 INFO - 'populate-webroot', 14:09:43 INFO - 'create-virtualenv', 14:09:43 INFO - 'install', 14:09:43 INFO - 'run-tests'), 14:09:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:09:43 INFO - 'download_minidump_stackwalk': True, 14:09:43 INFO - 'download_symbols': 'ondemand', 14:09:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:09:43 INFO - 'tooltool.py': '/tools/tooltool.py', 14:09:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:09:43 INFO - '/tools/misc-python/virtualenv.py')}, 14:09:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:09:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:09:43 INFO - 'installer_path': 'installer.exe', 14:09:43 INFO - 'log_level': 'info', 14:09:43 INFO - 'log_name': 'talos', 14:09:43 INFO - 'log_to_console': True, 14:09:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:09:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:09:43 INFO - 'opt_config_files': (), 14:09:43 INFO - 'pip_index': False, 14:09:43 INFO - 'sps_profile': False, 14:09:43 INFO - 'sps_profile_interval': 0, 14:09:43 INFO - 'suite': 'dromaeojs-e10s', 14:09:43 INFO - 'system_bits': '32', 14:09:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:09:43 INFO - 'title': 'talos-linux64-ix-071', 14:09:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:09:43 INFO - 'use_talos_json': True, 14:09:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:09:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:09:43 INFO - 'work_dir': 'build'} 14:09:43 INFO - [mozharness: 2017-02-17 22:09:43.521165Z] Running clobber step. 14:09:43 INFO - Running pre-action listener: _resource_record_pre_action 14:09:43 INFO - Running main action method: clobber 14:09:43 INFO - rmtree: /builds/slave/test/build 14:09:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:09:44 INFO - Running post-action listener: _resource_record_post_action 14:09:44 INFO - [mozharness: 2017-02-17 22:09:44.962516Z] Finished clobber step (success) 14:09:44 INFO - [mozharness: 2017-02-17 22:09:44.962616Z] Running read-buildbot-config step. 14:09:44 INFO - Running pre-action listener: _resource_record_pre_action 14:09:44 INFO - Running main action method: read_buildbot_config 14:09:44 INFO - Using buildbot properties: 14:09:44 INFO - { 14:09:44 INFO - "project": "", 14:09:44 INFO - "product": "firefox", 14:09:44 INFO - "who": "kwierso@gmail.com", 14:09:44 INFO - "installer_path": "public/build/target.tar.bz2", 14:09:44 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 14:09:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:09:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 14:09:44 INFO - "stage_platform": "linux64", 14:09:44 INFO - "basedir": "/builds/slave/test", 14:09:44 INFO - "buildnumber": 680, 14:09:44 INFO - "platform": "ubuntu64_hw", 14:09:44 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:09:44 INFO - "slavebuilddir": "test", 14:09:44 INFO - "taskId": "NSfcDssWR7-PXLa6xxBJAQ", 14:09:44 INFO - "branch": "mozilla-central", 14:09:44 INFO - "script_repo_revision": "production", 14:09:44 INFO - "revision": "0930fdc4cf8e0116e19577005f57f7ec12336ced", 14:09:44 INFO - "slavename": "talos-linux64-ix-071", 14:09:44 INFO - "repo_path": "mozilla-central" 14:09:44 INFO - } 14:09:44 INFO - Finding installer, test and symbols from parent task. 14:09:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NSfcDssWR7-PXLa6xxBJAQ'}, attempt #1 14:09:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NSfcDssWR7-PXLa6xxBJAQ'}, attempt #1 14:09:45 INFO - Task dependencies: MobKWfk2RVS1PNGQwQj7zg 14:09:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg'}, attempt #1 14:09:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 14:09:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/test_packages.json 14:09:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.crashreporter-symbols.zip 14:09:46 INFO - Running post-action listener: _resource_record_post_action 14:09:46 INFO - [mozharness: 2017-02-17 22:09:46.368331Z] Finished read-buildbot-config step (success) 14:09:46 INFO - [mozharness: 2017-02-17 22:09:46.368605Z] Running download-and-extract step. 14:09:46 INFO - Running pre-action listener: _resource_record_pre_action 14:09:46 INFO - Running main action method: download_and_extract 14:09:46 INFO - mkdir: /builds/slave/test/build/tests 14:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:09:46 INFO - trying https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.test_packages.json 14:09:46 INFO - Downloading https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:09:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:09:47 INFO - Downloaded 896 bytes. 14:09:47 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:09:47 INFO - Using the following test package requirements: 14:09:47 INFO - {u'common': [u'target.common.tests.zip'], 14:09:47 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:09:47 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:09:47 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:09:47 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:09:47 INFO - u'mozbase': [u'target.common.tests.zip'], 14:09:47 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:09:47 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:09:47 INFO - u'web-platform': [u'target.common.tests.zip', 14:09:47 INFO - u'target.web-platform.tests.zip'], 14:09:47 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:09:47 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:09:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip 14:09:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:09:47 INFO - Fetch https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip into memory 14:09:49 INFO - Expected file size: 57145682 14:09:49 INFO - Obtained file size: 57145682 14:09:58 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:09:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip 14:09:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:09:58 INFO - Fetch https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip into memory 14:10:00 INFO - Expected file size: 57145682 14:10:00 INFO - Obtained file size: 57145682 14:10:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.talos.tests.zip 14:10:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:10:10 INFO - Fetch https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.talos.tests.zip into memory 14:10:12 INFO - Expected file size: 14403647 14:10:12 INFO - Obtained file size: 14403647 14:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:12 INFO - trying https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 14:10:12 INFO - Downloading https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:10:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:10:14 INFO - Downloaded 61400565 bytes. 14:10:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 14:10:14 INFO - mkdir: /builds/slave/test/properties 14:10:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:10:14 INFO - Writing to file /builds/slave/test/properties/build_url 14:10:14 INFO - Contents: 14:10:14 INFO - build_url:https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 14:10:14 INFO - Running post-action listener: _resource_record_post_action 14:10:14 INFO - Running post-action listener: set_extra_try_arguments 14:10:14 INFO - [mozharness: 2017-02-17 22:10:14.763388Z] Finished download-and-extract step (success) 14:10:14 INFO - [mozharness: 2017-02-17 22:10:14.763486Z] Running populate-webroot step. 14:10:14 INFO - Running pre-action listener: _resource_record_pre_action 14:10:14 INFO - Running main action method: populate_webroot 14:10:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:10:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:10:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:10:14 INFO - '--tppagecycles', 14:10:14 INFO - '7'], 14:10:14 INFO - 'tests': ['tsvgm']}}, 14:10:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:10:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:10:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:10:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:10:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:10:14 INFO - 'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:10:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:10:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:10:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:10:14 INFO - 'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['damp', 'tps']}, 14:10:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:10:14 INFO - 'tests': ['damp', 'tps']}, 14:10:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['dromaeo_dom']}, 14:10:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:10:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['basic_compositor_video']}, 14:10:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:10:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['a11yr', 14:10:14 INFO - 'ts_paint', 14:10:14 INFO - 'tpaint', 14:10:14 INFO - 'sessionrestore', 14:10:14 INFO - 'sessionrestore_no_auto_restore', 14:10:14 INFO - 'tabpaint']}, 14:10:14 INFO - 'other-e10s': {'tests': ['a11yr', 14:10:14 INFO - 'ts_paint', 14:10:14 INFO - 'tpaint', 14:10:14 INFO - 'sessionrestore', 14:10:14 INFO - 'sessionrestore_no_auto_restore', 14:10:14 INFO - 'tabpaint']}, 14:10:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:10:14 INFO - 'ts_paint', 14:10:14 INFO - 'tpaint', 14:10:14 INFO - 'sessionrestore', 14:10:14 INFO - 'sessionrestore_no_auto_restore', 14:10:14 INFO - 'tabpaint']}, 14:10:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:10:14 INFO - 'ts_paint', 14:10:14 INFO - 'tpaint', 14:10:14 INFO - 'sessionrestore', 14:10:14 INFO - 'sessionrestore_no_auto_restore', 14:10:14 INFO - 'tabpaint']}, 14:10:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['a11yr', 14:10:14 INFO - 'ts_paint', 14:10:14 INFO - 'tpaint', 14:10:14 INFO - 'sessionrestore', 14:10:14 INFO - 'sessionrestore_no_auto_restore', 14:10:14 INFO - 'tabpaint']}, 14:10:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['a11yr', 14:10:14 INFO - 'ts_paint', 14:10:14 INFO - 'tpaint', 14:10:14 INFO - 'sessionrestore', 14:10:14 INFO - 'sessionrestore_no_auto_restore', 14:10:14 INFO - 'tabpaint']}, 14:10:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['tsvgx', 14:10:14 INFO - 'tsvgr_opacity', 14:10:14 INFO - 'tart', 14:10:14 INFO - 'tscrollx', 14:10:14 INFO - 'cart', 14:10:14 INFO - 'tsvg_static']}, 14:10:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:10:14 INFO - 'tsvgr_opacity', 14:10:14 INFO - 'tart', 14:10:14 INFO - 'tscrollx', 14:10:14 INFO - 'cart', 14:10:14 INFO - 'tsvg_static']}, 14:10:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:10:14 INFO - 'talos_options': ['--disable-e10s'], 14:10:14 INFO - 'tests': ['tp5o']}, 14:10:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:10:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:10:14 INFO - 'talos_options': ['--disable-e10s', 14:10:14 INFO - '--xperf_path', 14:10:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:10:14 INFO - 'tests': ['tp5n']}, 14:10:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:10:14 INFO - 'talos_options': ['--xperf_path', 14:10:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:10:14 INFO - 'tests': ['tp5n']}}, 14:10:14 INFO - 'talos.zip': {'path': '', 14:10:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:10:14 INFO - Running post-action listener: _resource_record_post_action 14:10:14 INFO - [mozharness: 2017-02-17 22:10:14.776740Z] Finished populate-webroot step (success) 14:10:14 INFO - [mozharness: 2017-02-17 22:10:14.776832Z] Running create-virtualenv step. 14:10:14 INFO - Running pre-action listener: _resource_record_pre_action 14:10:14 INFO - Running main action method: create_virtualenv 14:10:14 INFO - Creating virtualenv /builds/slave/test/build/venv 14:10:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:10:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:10:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:10:14 INFO - Using real prefix '/usr' 14:10:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:10:15 INFO - Installing distribute.............................................................................................................................................................................................done. 14:10:17 INFO - Installing pip.................done. 14:10:17 INFO - Return code: 0 14:10:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:10:17 INFO - Reading from file tmpfile_stdout 14:10:17 INFO - Output received: 14:10:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:10:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x14fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', '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 14:10:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 14:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:10:17 INFO - Using env: {'DISPLAY': ':0', 14:10:17 INFO - 'HOME': '/home/cltbld', 14:10:17 INFO - 'LANG': 'en_US.UTF-8', 14:10:17 INFO - 'LANGUAGE': 'en_US:en', 14:10:17 INFO - 'LOGNAME': 'cltbld', 14:10:17 INFO - 'MAIL': '/var/mail/cltbld', 14:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:17 INFO - 'MOZ_NO_REMOTE': '1', 14:10:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:17 INFO - 'NO_EM_RESTART': '1', 14:10:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:17 INFO - 'PWD': '/builds/slave/test', 14:10:17 INFO - 'SHELL': '/bin/bash', 14:10:17 INFO - 'SHLVL': '1', 14:10:17 INFO - 'TERM': 'linux', 14:10:17 INFO - 'TMOUT': '86400', 14:10:17 INFO - 'USER': 'cltbld', 14:10:17 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:17 INFO - '_': '/tools/buildbot/bin/python'} 14:10:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:10:17 INFO - Cleaning up... 14:10:17 INFO - Return code: 0 14:10:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x14fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', '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 14:10:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 14:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:10:17 INFO - Using env: {'DISPLAY': ':0', 14:10:17 INFO - 'HOME': '/home/cltbld', 14:10:17 INFO - 'LANG': 'en_US.UTF-8', 14:10:17 INFO - 'LANGUAGE': 'en_US:en', 14:10:17 INFO - 'LOGNAME': 'cltbld', 14:10:17 INFO - 'MAIL': '/var/mail/cltbld', 14:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:17 INFO - 'MOZ_NO_REMOTE': '1', 14:10:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:17 INFO - 'NO_EM_RESTART': '1', 14:10:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:17 INFO - 'PWD': '/builds/slave/test', 14:10:17 INFO - 'SHELL': '/bin/bash', 14:10:17 INFO - 'SHLVL': '1', 14:10:17 INFO - 'TERM': 'linux', 14:10:17 INFO - 'TMOUT': '86400', 14:10:17 INFO - 'USER': 'cltbld', 14:10:17 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:17 INFO - '_': '/tools/buildbot/bin/python'} 14:10:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:18 INFO - Downloading/unpacking psutil>=3.1.1 14:10:18 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 14:10:18 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 14:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:18 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 14:10:18 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 14:10:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:10:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:10:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:10:20 INFO - Installing collected packages: psutil 14:10:20 INFO - Running setup.py install for psutil 14:10:20 INFO - building 'psutil._psutil_linux' extension 14:10:20 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 14:10:20 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 14:10:20 INFO - building 'psutil._psutil_posix' extension 14:10:20 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 14:10:20 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 14:10:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:10:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:10:20 INFO - Successfully installed psutil 14:10:20 INFO - Cleaning up... 14:10:20 INFO - Return code: 0 14:10:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x14fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', '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 14:10:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 14:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:10:20 INFO - Using env: {'DISPLAY': ':0', 14:10:20 INFO - 'HOME': '/home/cltbld', 14:10:20 INFO - 'LANG': 'en_US.UTF-8', 14:10:20 INFO - 'LANGUAGE': 'en_US:en', 14:10:20 INFO - 'LOGNAME': 'cltbld', 14:10:20 INFO - 'MAIL': '/var/mail/cltbld', 14:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:20 INFO - 'MOZ_NO_REMOTE': '1', 14:10:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:20 INFO - 'NO_EM_RESTART': '1', 14:10:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:20 INFO - 'PWD': '/builds/slave/test', 14:10:20 INFO - 'SHELL': '/bin/bash', 14:10:20 INFO - 'SHLVL': '1', 14:10:20 INFO - 'TERM': 'linux', 14:10:20 INFO - 'TMOUT': '86400', 14:10:20 INFO - 'USER': 'cltbld', 14:10:20 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:20 INFO - '_': '/tools/buildbot/bin/python'} 14:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:10: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 14:10: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 14:10:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10: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 14:10: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 14:10:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:10:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:10:23 INFO - Installing collected packages: mozsystemmonitor 14:10:23 INFO - Running setup.py install for mozsystemmonitor 14:10:23 INFO - Successfully installed mozsystemmonitor 14:10:23 INFO - Cleaning up... 14:10:23 INFO - Return code: 0 14:10:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x14fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', '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 14:10:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 14:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:10:23 INFO - Using env: {'DISPLAY': ':0', 14:10:23 INFO - 'HOME': '/home/cltbld', 14:10:23 INFO - 'LANG': 'en_US.UTF-8', 14:10:23 INFO - 'LANGUAGE': 'en_US:en', 14:10:23 INFO - 'LOGNAME': 'cltbld', 14:10:23 INFO - 'MAIL': '/var/mail/cltbld', 14:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:23 INFO - 'MOZ_NO_REMOTE': '1', 14:10:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:23 INFO - 'NO_EM_RESTART': '1', 14:10:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:23 INFO - 'PWD': '/builds/slave/test', 14:10:23 INFO - 'SHELL': '/bin/bash', 14:10:23 INFO - 'SHLVL': '1', 14:10:23 INFO - 'TERM': 'linux', 14:10:23 INFO - 'TMOUT': '86400', 14:10:23 INFO - 'USER': 'cltbld', 14:10:23 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:23 INFO - '_': '/tools/buildbot/bin/python'} 14:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:23 INFO - Downloading/unpacking jsonschema==2.5.1 14:10:23 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 14:10:23 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 14:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:23 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 14:10:23 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 14:10:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:10:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:10:25 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 14:10:25 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 14:10:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:25 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 14:10:25 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 14:10:25 INFO - Downloading functools32-3.2.3-2.tar.gz 14:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:10:25 INFO - warning: no files found matching '*.txt' 14:10:25 INFO - no previously-included directories found matching 'build' 14:10:25 INFO - no previously-included directories found matching 'dist' 14:10:25 INFO - no previously-included directories found matching '.git*' 14:10:25 INFO - Installing collected packages: jsonschema, functools32 14:10:25 INFO - Running setup.py install for functools32 14:10:25 INFO - warning: no files found matching '*.txt' 14:10:25 INFO - no previously-included directories found matching 'build' 14:10:25 INFO - no previously-included directories found matching 'dist' 14:10:25 INFO - no previously-included directories found matching '.git*' 14:10:25 INFO - Successfully installed jsonschema functools32 14:10:25 INFO - Cleaning up... 14:10:26 INFO - Return code: 0 14:10:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x14fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', '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 14:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 14:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:10:26 INFO - Using env: {'DISPLAY': ':0', 14:10:26 INFO - 'HOME': '/home/cltbld', 14:10:26 INFO - 'LANG': 'en_US.UTF-8', 14:10:26 INFO - 'LANGUAGE': 'en_US:en', 14:10:26 INFO - 'LOGNAME': 'cltbld', 14:10:26 INFO - 'MAIL': '/var/mail/cltbld', 14:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:26 INFO - 'MOZ_NO_REMOTE': '1', 14:10:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:26 INFO - 'NO_EM_RESTART': '1', 14:10:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:26 INFO - 'PWD': '/builds/slave/test', 14:10:26 INFO - 'SHELL': '/bin/bash', 14:10:26 INFO - 'SHLVL': '1', 14:10:26 INFO - 'TERM': 'linux', 14:10:26 INFO - 'TMOUT': '86400', 14:10:26 INFO - 'USER': 'cltbld', 14:10:26 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:26 INFO - '_': '/tools/buildbot/bin/python'} 14:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:10:26 INFO - Cleaning up... 14:10:26 INFO - Return code: 0 14:10:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x14fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', '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 14:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 14:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:10:26 INFO - Using env: {'DISPLAY': ':0', 14:10:26 INFO - 'HOME': '/home/cltbld', 14:10:26 INFO - 'LANG': 'en_US.UTF-8', 14:10:26 INFO - 'LANGUAGE': 'en_US:en', 14:10:26 INFO - 'LOGNAME': 'cltbld', 14:10:26 INFO - 'MAIL': '/var/mail/cltbld', 14:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:26 INFO - 'MOZ_NO_REMOTE': '1', 14:10:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:26 INFO - 'NO_EM_RESTART': '1', 14:10:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:26 INFO - 'PWD': '/builds/slave/test', 14:10:26 INFO - 'SHELL': '/bin/bash', 14:10:26 INFO - 'SHLVL': '1', 14:10:26 INFO - 'TERM': 'linux', 14:10:26 INFO - 'TMOUT': '86400', 14:10:26 INFO - 'USER': 'cltbld', 14:10:26 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:26 INFO - '_': '/tools/buildbot/bin/python'} 14:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:26 INFO - Downloading/unpacking blobuploader==1.2.4 14:10:26 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 14:10:26 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 14:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:26 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 14:10:26 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 14:10:28 INFO - Downloading blobuploader-1.2.4.tar.gz 14:10:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:10:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:10:28 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 14:10:28 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 14:10:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:28 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 14:10:28 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 14:10:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:10:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:10:28 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 14:10:28 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 14:10:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:28 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 14:10:28 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 14:10:29 INFO - Downloading docopt-0.6.1.tar.gz 14:10:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:10:29 INFO - Installing collected packages: blobuploader, requests, docopt 14:10:29 INFO - Running setup.py install for blobuploader 14:10:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:10:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:10:29 INFO - Running setup.py install for requests 14:10:29 INFO - Running setup.py install for docopt 14:10:29 INFO - Successfully installed blobuploader requests docopt 14:10:29 INFO - Cleaning up... 14:10:29 INFO - Return code: 0 14:10:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:29 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x14fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', '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 14:10:29 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 14:10:29 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:10:29 INFO - Using env: {'DISPLAY': ':0', 14:10:29 INFO - 'HOME': '/home/cltbld', 14:10:29 INFO - 'LANG': 'en_US.UTF-8', 14:10:29 INFO - 'LANGUAGE': 'en_US:en', 14:10:29 INFO - 'LOGNAME': 'cltbld', 14:10:29 INFO - 'MAIL': '/var/mail/cltbld', 14:10:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:29 INFO - 'MOZ_NO_REMOTE': '1', 14:10:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:29 INFO - 'NO_EM_RESTART': '1', 14:10:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:29 INFO - 'PWD': '/builds/slave/test', 14:10:29 INFO - 'SHELL': '/bin/bash', 14:10:29 INFO - 'SHLVL': '1', 14:10:29 INFO - 'TERM': 'linux', 14:10:29 INFO - 'TMOUT': '86400', 14:10:29 INFO - 'USER': 'cltbld', 14:10:29 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:29 INFO - '_': '/tools/buildbot/bin/python'} 14:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:10:30 INFO - Running setup.py (path:/tmp/pip-SMNEEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:10:30 INFO - Running setup.py (path:/tmp/pip-hYV7aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:10:30 INFO - Running setup.py (path:/tmp/pip-kKji2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:10:30 INFO - Running setup.py (path:/tmp/pip-1aQUdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:10:30 INFO - Running setup.py (path:/tmp/pip-QNrk6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:10:30 INFO - Running setup.py (path:/tmp/pip-sv0MsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:10:30 INFO - Running setup.py (path:/tmp/pip-2giiFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:10:30 INFO - Running setup.py (path:/tmp/pip-16CMVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:10:30 INFO - Running setup.py (path:/tmp/pip-mHKJi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:10:30 INFO - Running setup.py (path:/tmp/pip-51fOaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:10:30 INFO - Running setup.py (path:/tmp/pip-dndUoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:10:30 INFO - Running setup.py (path:/tmp/pip-ab5lrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:10:30 INFO - Running setup.py (path:/tmp/pip-f3lt9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:10:31 INFO - Running setup.py (path:/tmp/pip-FjUK5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:10:31 INFO - Running setup.py (path:/tmp/pip-vFGgxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:10:31 INFO - Running setup.py (path:/tmp/pip-B5SxWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:10:31 INFO - Running setup.py (path:/tmp/pip-0y4QLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:10:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:10:31 INFO - Running setup.py install for manifestparser 14:10:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:10:31 INFO - Running setup.py install for mozcrash 14:10:31 INFO - Running setup.py install for mozdebug 14:10:31 INFO - Running setup.py install for mozdevice 14:10:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:10:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:10:31 INFO - Running setup.py install for mozfile 14:10:31 INFO - Running setup.py install for mozhttpd 14:10:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:10:31 INFO - Running setup.py install for mozinfo 14:10:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Running setup.py install for mozInstall 14:10:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Running setup.py install for mozleak 14:10:32 INFO - Running setup.py install for mozlog 14:10:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Running setup.py install for moznetwork 14:10:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Running setup.py install for mozprocess 14:10:32 INFO - Running setup.py install for mozprofile 14:10:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Running setup.py install for mozrunner 14:10:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:10:32 INFO - Running setup.py install for mozscreenshot 14:10:32 INFO - Running setup.py install for moztest 14:10:33 INFO - Running setup.py install for mozversion 14:10:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:10:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:10:33 INFO - Cleaning up... 14:10:33 INFO - Return code: 0 14:10:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x14fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', '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 14:10:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 14:10:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:10:33 INFO - Using env: {'DISPLAY': ':0', 14:10:33 INFO - 'HOME': '/home/cltbld', 14:10:33 INFO - 'LANG': 'en_US.UTF-8', 14:10:33 INFO - 'LANGUAGE': 'en_US:en', 14:10:33 INFO - 'LOGNAME': 'cltbld', 14:10:33 INFO - 'MAIL': '/var/mail/cltbld', 14:10:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:33 INFO - 'MOZ_NO_REMOTE': '1', 14:10:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:33 INFO - 'NO_EM_RESTART': '1', 14:10:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:33 INFO - 'PWD': '/builds/slave/test', 14:10:33 INFO - 'SHELL': '/bin/bash', 14:10:33 INFO - 'SHLVL': '1', 14:10:33 INFO - 'TERM': 'linux', 14:10:33 INFO - 'TMOUT': '86400', 14:10:33 INFO - 'USER': 'cltbld', 14:10:33 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:33 INFO - '_': '/tools/buildbot/bin/python'} 14:10:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:10:33 INFO - Running setup.py (path:/tmp/pip-Ux5AkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:10:33 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)) 14:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:10:33 INFO - Running setup.py (path:/tmp/pip-600zDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:10:33 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)) 14:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:10:33 INFO - Running setup.py (path:/tmp/pip-JGG3vR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:10:33 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)) 14:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:10:33 INFO - Running setup.py (path:/tmp/pip-pyJdLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 14:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:10:33 INFO - Running setup.py (path:/tmp/pip-JWiDfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:10:33 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)) 14:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:10:33 INFO - Running setup.py (path:/tmp/pip-9euS2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:10:33 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)) 14:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:10:33 INFO - Running setup.py (path:/tmp/pip-9W2Jdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 14:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:10:33 INFO - Running setup.py (path:/tmp/pip-cjAcuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 14:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:10:33 INFO - Running setup.py (path:/tmp/pip-bKW8is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:10:34 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)) 14:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:10:34 INFO - Running setup.py (path:/tmp/pip-zw3cOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:10:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 14:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:10:34 INFO - Running setup.py (path:/tmp/pip-2C20w7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:10:34 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)) 14:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:10:34 INFO - Running setup.py (path:/tmp/pip-soSKvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:10:34 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)) 14:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:10:34 INFO - Running setup.py (path:/tmp/pip-09A0dv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:10:34 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)) 14:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:10:34 INFO - Running setup.py (path:/tmp/pip-TLvDQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:10:34 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)) 14:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:10:34 INFO - Running setup.py (path:/tmp/pip-0v7jt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:10:34 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)) 14:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:10:34 INFO - Running setup.py (path:/tmp/pip-kWf_7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:10:34 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)) 14:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:10:34 INFO - Running setup.py (path:/tmp/pip-IGs38M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:10:34 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)) 14:10:34 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)) 14:10:34 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)) 14:10:34 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)) 14:10:34 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:10:34 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:10:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:10:34 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 14:10:34 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 14:10:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:34 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 14:10:34 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 14:10:36 INFO - Downloading blessings-1.6.tar.gz 14:10:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:10:36 INFO - Installing collected packages: blessings 14:10:36 INFO - Running setup.py install for blessings 14:10:36 INFO - Successfully installed blessings 14:10:36 INFO - Cleaning up... 14:10:36 INFO - Return code: 0 14:10:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:10:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:10:37 INFO - Reading from file tmpfile_stdout 14:10:37 INFO - Current package versions: 14:10:37 INFO - argparse == 1.2.1 14:10:37 INFO - blessings == 1.6 14:10:37 INFO - blobuploader == 1.2.4 14:10:37 INFO - docopt == 0.6.1 14:10:37 INFO - functools32 == 3.2.3-2 14:10:37 INFO - jsonschema == 2.5.1 14:10:37 INFO - manifestparser == 1.1 14:10:37 INFO - mozInstall == 1.12 14:10:37 INFO - mozcrash == 1.0 14:10:37 INFO - mozdebug == 0.1 14:10:37 INFO - mozdevice == 0.49 14:10:37 INFO - mozfile == 1.2 14:10:37 INFO - mozhttpd == 0.7 14:10:37 INFO - mozinfo == 0.9 14:10:37 INFO - mozleak == 0.1 14:10:37 INFO - mozlog == 3.4 14:10:37 INFO - moznetwork == 0.27 14:10:37 INFO - mozprocess == 0.25 14:10:37 INFO - mozprofile == 0.28 14:10:37 INFO - mozrunner == 6.13 14:10:37 INFO - mozscreenshot == 0.1 14:10:37 INFO - mozsystemmonitor == 0.3 14:10:37 INFO - moztest == 0.8 14:10:37 INFO - mozversion == 1.4 14:10:37 INFO - psutil == 3.1.1 14:10:37 INFO - requests == 1.2.3 14:10:37 INFO - wsgiref == 0.1.2 14:10:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:37 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x14fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', '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 14:10:37 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 14:10:37 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:10:37 INFO - Using env: {'DISPLAY': ':0', 14:10:37 INFO - 'HOME': '/home/cltbld', 14:10:37 INFO - 'LANG': 'en_US.UTF-8', 14:10:37 INFO - 'LANGUAGE': 'en_US:en', 14:10:37 INFO - 'LOGNAME': 'cltbld', 14:10:37 INFO - 'MAIL': '/var/mail/cltbld', 14:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:37 INFO - 'MOZ_NO_REMOTE': '1', 14:10:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:37 INFO - 'NO_EM_RESTART': '1', 14:10:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:37 INFO - 'PWD': '/builds/slave/test', 14:10:37 INFO - 'SHELL': '/bin/bash', 14:10:37 INFO - 'SHLVL': '1', 14:10:37 INFO - 'TERM': 'linux', 14:10:37 INFO - 'TMOUT': '86400', 14:10:37 INFO - 'USER': 'cltbld', 14:10:37 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:37 INFO - '_': '/tools/buildbot/bin/python'} 14:10:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:37 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)) 14:10:37 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)) 14:10:37 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)) 14:10:37 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)) 14:10:37 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)) 14:10:37 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)) 14:10:37 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)) 14:10:37 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)) 14:10:37 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)) 14:10:37 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)) 14:10:37 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)) 14:10:37 INFO - Cleaning up... 14:10:37 INFO - Return code: 0 14:10:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:37 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x14fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', '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 14:10:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:10:37 INFO - Using env: {'DISPLAY': ':0', 14:10:37 INFO - 'HOME': '/home/cltbld', 14:10:37 INFO - 'LANG': 'en_US.UTF-8', 14:10:37 INFO - 'LANGUAGE': 'en_US:en', 14:10:37 INFO - 'LOGNAME': 'cltbld', 14:10:37 INFO - 'MAIL': '/var/mail/cltbld', 14:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:37 INFO - 'MOZ_NO_REMOTE': '1', 14:10:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:37 INFO - 'NO_EM_RESTART': '1', 14:10:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:37 INFO - 'PWD': '/builds/slave/test', 14:10:37 INFO - 'SHELL': '/bin/bash', 14:10:37 INFO - 'SHLVL': '1', 14:10:37 INFO - 'TERM': 'linux', 14:10:37 INFO - 'TMOUT': '86400', 14:10:37 INFO - 'USER': 'cltbld', 14:10:37 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:37 INFO - '_': '/tools/buildbot/bin/python'} 14:10:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:10:37 INFO - Cleaning up... 14:10:37 INFO - Return code: 0 14:10:37 INFO - Running post-action listener: _resource_record_post_action 14:10:37 INFO - Running post-action listener: _start_resource_monitoring 14:10:37 INFO - Starting resource monitoring. 14:10:37 INFO - [mozharness: 2017-02-17 22:10:37.549908Z] Finished create-virtualenv step (success) 14:10:37 INFO - [mozharness: 2017-02-17 22:10:37.550377Z] Running install step. 14:10:37 INFO - Running pre-action listener: _resource_record_pre_action 14:10:37 INFO - Running main action method: install 14:10:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:10:37 INFO - Reading from file tmpfile_stdout 14:10:37 INFO - Detecting whether we're running mozinstall >=1.0... 14:10:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:10:37 INFO - Reading from file tmpfile_stdout 14:10:37 INFO - Output received: 14:10:37 INFO - Usage: mozinstall [options] installer 14:10:37 INFO - Options: 14:10:37 INFO - -h, --help show this help message and exit 14:10:37 INFO - -d DEST, --destination=DEST 14:10:37 INFO - Directory to install application into. [default: 14:10:37 INFO - "/builds/slave/test"] 14:10:37 INFO - --app=APP Application being installed. [default: firefox] 14:10:37 INFO - mkdir: /builds/slave/test/build/application 14:10:37 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'] 14:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:10:53 INFO - Reading from file tmpfile_stdout 14:10:53 INFO - Output received: 14:10:53 INFO - /builds/slave/test/build/application/firefox/firefox 14:10:53 INFO - Running post-action listener: _resource_record_post_action 14:10:53 INFO - [mozharness: 2017-02-17 22:10:53.233641Z] Finished install step (success) 14:10:53 INFO - [mozharness: 2017-02-17 22:10:53.233918Z] Running run-tests step. 14:10:53 INFO - Running pre-action listener: _resource_record_pre_action 14:10:53 INFO - Running main action method: run_tests 14:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:10:53 INFO - Python 2.7.3 14:10:53 INFO - Return code: 0 14:10:53 INFO - grabbing minidump binary from tooltool 14:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:10:53 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x1799290>, '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 0x1799b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x179a040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:10:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 14:10:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:10:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 14:10:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:10:53 INFO - Return code: 0 14:10:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:10:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:10:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:10:53 INFO - ENV: RUST_BACKTRACE is now 1 14:10:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:10:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:10:53 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/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--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 14:10:53 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/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-071 --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 14:10:53 INFO - Using env: {'DISPLAY': ':0', 14:10:53 INFO - 'HOME': '/home/cltbld', 14:10:53 INFO - 'LANG': 'en_US.UTF-8', 14:10:53 INFO - 'LANGUAGE': 'en_US:en', 14:10:53 INFO - 'LOGNAME': 'cltbld', 14:10:53 INFO - 'MAIL': '/var/mail/cltbld', 14:10:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:10:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:53 INFO - 'MOZ_NO_REMOTE': '1', 14:10:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:53 INFO - 'NO_EM_RESTART': '1', 14:10:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:53 INFO - 'PWD': '/builds/slave/test', 14:10:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:10:53 INFO - 'RUST_BACKTRACE': '1', 14:10:53 INFO - 'SHELL': '/bin/bash', 14:10:53 INFO - 'SHLVL': '1', 14:10:53 INFO - 'TERM': 'linux', 14:10:53 INFO - 'TMOUT': '86400', 14:10:53 INFO - 'USER': 'cltbld', 14:10:53 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542', 14:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:53 INFO - '_': '/tools/buildbot/bin/python'} 14:10:53 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/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--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 14:10:53 INFO - mozversion application_buildid: 20170217214152 14:10:53 INFO - mozversion application_changeset: 0930fdc4cf8e0116e19577005f57f7ec12336ced 14:10:53 INFO - mozversion application_display_name: Nightly 14:10:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:10:53 INFO - mozversion application_name: Firefox 14:10:53 INFO - mozversion application_remotingname: firefox 14:10:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:10:53 INFO - mozversion application_vendor: Mozilla 14:10:53 INFO - mozversion application_version: 54.0a1 14:10:53 INFO - mozversion platform_buildid: 20170217214152 14:10:53 INFO - mozversion platform_changeset: 0930fdc4cf8e0116e19577005f57f7ec12336ced 14:10:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:10:53 INFO - mozversion platform_version: 54.0a1 14:10:53 INFO - using testdate: 1487369453 14:10:53 INFO - actual date: 1487369453 14:10:53 INFO - starting webserver on 'localhost:41234' 14:10:53 INFO - SUITE-START | Running 2 tests 14:10:53 INFO - TEST-START | dromaeo_css 14:10:53 INFO - operating with platform_type : linux_ 14:10:53 INFO - Initialising browser for dromaeo_css test... 14:10:53 INFO - TEST-INFO | started process 15504 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVTJcca/profile http://localhost:41234/getInfo.html) 14:10:56 INFO - PROCESS | 15504 | Iterating over profile /tmp/tmpVTJcca/profile 14:10:57 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:57 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/key3.db = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:57 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:57 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/places.sqlite = Fri Feb 17 2017 14:10:57 GMT-0800 (PST) 14:10:57 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:57 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/content-prefs.sqlite = Fri Feb 17 2017 14:10:55 GMT-0800 (PST) 14:10:57 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:57 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/secmod.db = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/thumbnails = Fri Feb 17 2017 14:10:56 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/frequencyCap.json = Fri Feb 17 2017 14:10:56 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/addons.json = Fri Feb 17 2017 14:10:56 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | __metrics Screen width/height:1600/1200 14:10:58 INFO - PROCESS | 15504 | colorDepth:24 14:10:58 INFO - PROCESS | 15504 | Browser inner width/height: 1024/697 14:10:58 INFO - PROCESS | 15504 | __metrics 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/bookmarkbackups = Fri Feb 17 2017 14:10:56 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/blocklist.xml = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/compatibility.ini = Fri Feb 17 2017 14:10:54 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/user.js = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/permissions.sqlite = Fri Feb 17 2017 14:10:54 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/places.sqlite-wal = Fri Feb 17 2017 14:10:58 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/extensions.json = Fri Feb 17 2017 14:10:56 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/pluginreg.dat = Fri Feb 17 2017 14:10:56 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/startupCache = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/prefs.js = Fri Feb 17 2017 14:10:12 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/localstore.rdf = Fri Feb 17 2017 14:10:12 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/directoryLinks.json = Fri Feb 17 2017 14:10:56 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/crashes = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/extensions = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/extensions.ini = Fri Feb 17 2017 14:10:54 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/gmp = Fri Feb 17 2017 14:10:54 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/minidumps = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/cache2 = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/places.sqlite-shm = Fri Feb 17 2017 14:10:55 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/.parentlock = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/safebrowsing = Fri Feb 17 2017 14:10:58 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/cert8.db = Fri Feb 17 2017 14:10:53 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | No birth date. Using mtime. 14:10:58 INFO - PROCESS | 15504 | Using date: /tmp/tmpVTJcca/profile/sessionCheckpoints.json = Fri Feb 17 2017 14:10:57 GMT-0800 (PST) 14:10:58 INFO - PROCESS | 15504 | Stat failure 14:10:59 ERROR - PROCESS | 15504 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 14:11:00 INFO - TEST-INFO | 15504: exit 0 14:11:00 INFO - Browser initialized. 14:11:00 INFO - Running cycle 1/1 for dromaeo_css test... 14:11:00 INFO - TEST-INFO | started process 15630 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVTJcca/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:11:12 INFO - PROCESS | 15630 | RSS: Main: 152985600 14:11:12 INFO - PROCESS | 15630 | 14:14:16 INFO - PROCESS | 15630 | Cycle 1(1): loaded http://localhost:41234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41234/tests/dromaeo/cssquery-ext.html) 14:14:18 INFO - PROCESS | 15630 | RSS: Main: 162607104 14:14:18 INFO - PROCESS | 15630 | 14:16:00 INFO - PROCESS | 15630 | ************************* 14:16:00 INFO - PROCESS | 15630 | A coding exception was thrown and uncaught in a Task. 14:16:00 INFO - PROCESS | 15630 | 14:16:00 ERROR - PROCESS | 15630 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:16:00 INFO - PROCESS | 15630 | Full stack: 14:16:00 INFO - PROCESS | 15630 | ************************* 14:17:20 INFO - PROCESS | 15630 | Cycle 1(1): loaded http://localhost:41234/tests/dromaeo/cssquery-ext.html (next: http://localhost:41234/tests/dromaeo/cssquery-jquery.html) 14:17:21 INFO - PROCESS | 15630 | RSS: Main: 157958144 14:17:21 INFO - PROCESS | 15630 | 14:20:23 INFO - PROCESS | 15630 | Cycle 1(1): loaded http://localhost:41234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41234/tests/dromaeo/cssquery-mootools.html) 14:20:24 INFO - PROCESS | 15630 | RSS: Main: 165978112 14:20:24 INFO - PROCESS | 15630 | 14:23:26 INFO - PROCESS | 15630 | Cycle 1(1): loaded http://localhost:41234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41234/tests/dromaeo/cssquery-prototype.html) 14:23:26 INFO - PROCESS | 15630 | RSS: Main: 160071680 14:23:26 INFO - PROCESS | 15630 | 14:26:29 INFO - PROCESS | 15630 | Cycle 1(1): loaded http://localhost:41234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41234/tests/dromaeo/cssquery-yui.html) 14:26:30 INFO - PROCESS | 15630 | RSS: Main: 163999744 14:26:30 INFO - PROCESS | 15630 | 14:29:33 INFO - PROCESS | 15630 | Cycle 1(1): loaded http://localhost:41234/tests/dromaeo/cssquery-yui.html (next: http://localhost:41234/tests/dromaeo/cssquery-dojo.html) 14:29:33 INFO - PROCESS | 15630 | RSS: Main: 163459072 14:29:33 INFO - PROCESS | 15630 | 14:29:33 INFO - PROCESS | 15630 | __start_tp_report 14:29:33 INFO - PROCESS | 15630 | _x_x_mozilla_page_load 14:29:33 INFO - PROCESS | 15630 | _x_x_mozilla_page_load_details 14:29:33 INFO - PROCESS | 15630 | |i|pagename|runs| 14:29:33 INFO - PROCESS | 15630 | |0;dojo.html;2616.42438663494;2617.097101499983;2618.3061488705507;2644.986775066125;2664.9999999999986;4903.19097348937;4912.312276281324;4918.336024636673;4944;4960.950390496094;4889.559939605444;4931.358923339961;4951.331570238017;4994.275830004647;4997.975010124945;4269.423627810237;4493.842715504966;4572.154151481986;4621.745803980796;4631;4560.680752347337;4587.747673877935;4591.494935557086;4609.03210325832;4633.397658304432;4319.136172765459;4346.9347959780625;4446.110777844444;4497.392851965008;4523.660725445605;4466.598006179427;4473.932891006638;4490.169318676056;4544.840930567448;4562.133194692998;4430.556944305576;4455.331700244957;4472.016156445577;4472.239719247736;4502.797374118172;4719.174144524694;4747.501512341189;4874.975625121887;4906.141425250603;4918.286848406961;5130.538251557377;5143.408508021551;5165.793368265265;5171.250168725552;5200.037992971313;5158.2520534522655;5181.533661970403;5188.429272779991;5192.7403629818355;5234.26720259164;4791.808327666924;4856.17445034345;4861.27080937855;4865.026994601094;4866.075445665348;5236.371635403776;5310.707911064928;5316.893662126736;5325.307709997753;5326.121190003683;5005.074061298671;5017.774200161001;5029.27075574047;5060.671056381323;5098.643094983316;5029.673071250357;5043.697378157322;5049.141645920203;5054.620903432257;5071.061853557104;4896.681715688469;5058.1148299048255;5096.974515127475;5098.719570423662;5117.2068329409;4900.215965445512;5146.948530514722;5149.922751158736;5171.095058364846;5269.866978599543;4447.199504089231;4455.1757924784015;4455.309427038814;4466.798994045275;4517.209488339593;3856.0745421099296;3856.9779008562755;3858.3247931611854;3893.785841778673;3909.1204478992568;4395.978020109879;4435.223835828717;4535.841245556389;4574.359589657451;4575.794089265924;5269.710165940834;5319.148936170194;5329.280547126088;5329.786808527616;5359.624826262202;4580.038191979722;4586.220342541709;4600.309953506934;4615.446146462379;4625.676202665781;4602.631789456836;4639.9536004640195;4643.6517261205545;4669.813207471663;4827;4814.470408255159;4848.927266090941;4856.587190088882;4860.684055536379;4865.221564549796;5157.7679004444135;5170.0952333340765;5183.455737147545;5192.013517431676;5207.635465517529;4865.635077369141;4866.95133048665;4890.462049174517;4912.926306105483;4915.8033678652455;5052;5119.6416250862085;5129.051125541787;5131.358580177478;5136.1268584340005;5087.618428617942;5146.8970620588125;5213.921791173211;5221.373435187802;5250.579953603779;5081.983603279433;5158.793648254028;5164.32863727728;5192.3769147702515;5207.2449494823795;5113.284140220298;5132.820351287687;5143.819966301311;5147.948520514747;5195.350581177353;4839.322494850653;4871.561559459666;5035.420926593489;5059.822906198266;5093.898122037613;5090.745462726923;5125.923111153261;5128.871778205575;5134.743262836918;5216.295800067094;4983.70097794119;4986.202207646759;4994.5754610858485;5008.949910500848;5035.370578677665 14:29:33 INFO - PROCESS | 15630 | |1;ext.html;526.3263023330136;538.6229639252523;538.9137737961927;553.1978630985072;591.4469970577513;34029.489557656656;38202.23595528088;38252.23495530095;38257.61742382575;38328.233435331276;37916.01881802406;38512.80743596278;38543.22913541728;38615.42076868842;38615.61384386155;7539.736109236178;7710.306072453478;7719.922800772005;9237;9620.807583848318;9371.156595906366;9462.053794620551;9466.763330916749;9472.952635236848;9481.573329200202;9413.388129771578;9467;9468.573914173845;9485.715428537154;9490.05099490049;9391.577379017961;9422.481763503005;9462.71611851642;9470.384425012353;9473.857892131555;9339.206167475773;9361.344705870591;9390.154886060287;9413.57638906251;9433;32471.675283247103;32622.67377326244;32702;32785.83607081973;32867.17832054194;3299.7525185610934;3379.3410284994434;3415.316936612687;3454.4990976308545;3465.1683595937047;25258.368540786443;25320.113796017235;25403.110891118722;25542.361440964123;25597.488050239077;3419.230673098558;3455.3953058214965;3580.6777390034763;3593.802340871251;3612.0608641753333;213394;214078;214233.5018274353;215264.9236753822;215504;38422.615773841906;39557.20885582272;45645.543544564796;45681.31478027832;46076;44553;48075;48259.517404826205;48396.5160348392;48415.273770893466;12532;12582.874171258354;12826.55107071248;12876;12894.871051289554;8978.86531702025;9058.456492610332;9266.351355405057;9304.25565954722;9381.812363752822;15534.533963981099;15604.21978901073;15618.3752649895;15663.295151717972;15730.68538629221;6285.937140628626;6343.365663433619;6525;6544.44372228366;6600.900986485207;742.7548908860064;772.4631381190019;772.6754762999553;795.0737390939606;799.4843326054778;25562.10532631349;25852.095176668725;26628.201153965412;26903.86548067247;27094.322641933715;887.7070566712969;926.4533924984291;930.5626355612851;946.9005754395827;953.3088510829732;13806.723865522834;14086.577402677936;14316.864806738597;14357.928210358881;14486.710265794625;326096;327947;329194;329908;330487;47686.76156619195;48134.759326203144;48154.27768583474;48263.27605085926;48323;1133.331334512625;1175.9647210583696;1192.7316353820322;1192.8986036186848;1194.378922960056;24361.26916192517;25165.205394543736;26438.735612644396;26476.205713828615;26487.470250595266;39909;40266.396004059374;40284;40331.19337613173;40483.36858104214;264646;266261;266684;267316;267626;24165;34957.26068482879;38296.808515957244;38447.615523844404;41330.38004429996;37380;38835.611643883196;40448.79775601103;40930.590694093866;41756.58243417648;35781.46327805137;35986.64013359833;36188.819055904554;36629.81685091557;36980.81509592435;34379.82810085933;34439.82780086083;34934.82532587321;35153.296934061684;35514.11214719652;34238.657613423544;35011.82494087513;35550.82224588861;35752.82123589469;35903;31137.53293700551;32000.67999319977;32572.51141232836;32646;32913.54407787148;24082.277531674077;24489;24664.506709866062;25102.11734119912;25467.36331591725 14:29:33 INFO - PROCESS | 15630 | |2;jquery.html;381.77475289579127;394.8144372145092;395.05581659833024;397.24919901386386;417.3760228458455;12576.308303043328;13264.867351326508;15422.691546169084;18019.099045047744;18097.728534072;16267.918660406682;16803.579910502245;17260.395886143986;18212.817871821277;18440.53898652531;4877.804887804493;5141.794328226866;5177.948220517803;5298.463445600791;5328.120860058086;5103.974480127594;5201.115810312237;5212;5214.061468935609;5225.529702326789;4976.079425306312;5118.462561431052;5121.795128194868;5168.844934651947;5175.689458632494;5094.337736094302;5162.586993040585;5178.291163915898;5183.429822719517;5187.221916712504;4496.437945256843;4562.543745625444;4609.608183304423;4611.608013318872;4613.515580864028;21366.786332136635;21459.67810482844;21652;21702.574356410896;21712.34862954114;2553.093651753638;2591.7926565874686;2620.882060307291;2655.6016597510334;2656.3491944473535;13404.798928016087;13459.798103028463;13605.115667481681;13694.178349298974;14310.64223394413;1716.1933891071176;1722.6382459683482;1726.231826837058;1738.939137130207;1740.782402199725;77604;77749.22250777476;77860.22139778586;78824.60587697082;78973;10135.493225338703;10322.432266225429;10323.94838025805;10357.844632330522;10525.684229473129;12081.45633446497;14484.130952142905;14513.709725805636;14518.564443066725;14541.491047813275;6725.6212476442215;6819.863602727918;6878.65606719662;7020;7152.749653762094;6499.610023398611;6513.869722605521;6579.342065793382;6729.966350168218;6759.662016899136;4032.5967403260024;4094.959050409517;4121.5878412159145;4153.210889930903;4178.937315940263;1932.748742663473;1971.9309824156373;2015.1637070615823;2021.6563184258712;2039.6124736300353;922.3773952582114;955.9474228917476;959.3764053365229;959.4243453927727;961.1925982174866;16622.66754664924;17224.827751722572;17727.11364431773;17769.46691599254;17808.643827123386;918.150710592699;921.801657844799;939.5771902643837;945;946.0728486083676;9401.388909720847;9437.292203084797;9668.129868311886;9684.370515916444;9765.951170244103;319162;327595;328826;468674;484651;86371.56814216015;88927;89475.55262223647;90151.54924225337;90667;921.3090182363228;922.1838672774509;974.6198982396871;975.5463709375033;981.0581841432305;15759.133247671489;15880.364785408916;16414.507564773077;16421.91789041047;16864.15679216059;29179.416411671224;33388.66611333856;34999.65000350066;37801.81099094597;39128.2174356517;47933.52066479291;48001;49346.50653493419;49432.50567494423;50969.490305097956;7454.92545074564;7669.23307669251;7739.458237923292;7796.415268854745;7851.136374998631;11225.214235003472;11846.289222646668;12279.877201227873;12424.254544727346;12546.1844980076;9693.854592181264;9768.755781105529;10070.899291006996;10126.848097278695;10201.74495637615;9919.553620087258;10046.296759226783;10087.344322619007;10145.391276523433;10172.186225102123;9608.087231712976;9627.614895404105;10031.648892288735;10042.49787510636;10261.9486902565;7628.160902300853;7671.846563068819;7708.421868359784;7732.188120247518;7906.130325664058;9028.27773778083;9576.952115239379;9654.275929305471;9785.412875227225;10054.69835904924 14:29:33 INFO - PROCESS | 15630 | |3;mootools.html;1309.731505041466;1345.3676771917198;1346.6767975685839;1352.9526466573666;1356.905016648835;7189.173245076818;7480.962595187017;7556.093268807744;7569.697212111516;7687.346575541072;7614;7773.961130194355;7774.455788094836;7778.611069446533;7816.140224575292;2073.688946658002;2099.496120930973;2106.083853523722;2109.219588752164;2111.1660893946882;2051.4471335342814;2089.8432617553744;2092.4559614500263;2096.821770149539;2098.0453893478507;2052.9486762830898;2059.1454546363243;2073.139647046474;2082.0422605601443;2084.687296905461;2016.4656366063004;2018.8082132197417;2029.8071683190074;2036.216056818121;2045.4681782736448;1724.1379310344828;1776.7423723560141;1997.9001049947444;2009.9095540700703;2090.8118269355678;7213.819654508627;7451.2921272479025;7510.586917719524;7654.234576542357;7715.382769378436;4288.206681763883;4402.273624851896;4405.493368262659;4419.889502762425;4435.401220835178;2701.405690748032;2703.1079743684536;2738.6165936768866;2765.6542932133484;2772.2930652683435;999.3504222255519;1003.6537394598852;1007.4358359318732;1078.6710053433699;1105.8728246251703;10056.849147262797;10157.187425005981;10215.182785377152;10292.845607315896;10300.587976480932;3660.890173294752;3778.6221377862544;3846.673032792188;3875.2443273561385;3895.2988462077096;8034.678612855537;8043.477173983672;8064.879026814602;8065.798355041054;8075.515469071875;4371.715838470489;4391.165678521071;4401.449818772653;4409.845655402046;4416.580424859633;3907.4724912137067;4053.533843608023;4193.727407718488;4196.391523229115;4216.114615930689;3184.792988455743;3233.11089450403;3310.668933106718;3314.171457135716;3318.867245310209;973.4013581647235;976.2970661123979;981.3326937682444;994.5822754443006;995.7411073121041;736.5838301359714;751.9248075192546;766.7853001159684;775.4571799740091;787.00836945448;4476.910461790746;4868.440129385095;4876.366072410564;4982.327385802942;5122.743862806845;847.7075408983891;882.1178821178821;884.6372987075268;885.6324625280565;887.7780554861284;3226.6773322667545;3289.73682105431;3311.4536101542976;3354.630990591033;3397.8470968806464;7876.606169691492;8098.878516822252;8191.631376588068;8618.353623478264;9099.772505687411;8296.460730052528;8515.276201522816;8522.701705440404;8531.829363412697;8534.487930724177;7086.078809754802;7147.3210045045635;7172.641367931583;7278.235785242685;7322.487425880244;4970.850874473772;5000.849974500771;5106.106431374569;5181.715005674724;5202.193659982709;8010.599470026592;8308.129250853237;8347.54088525137;8383.371247156365;8394.034686010942;8409.53747543891;8476.15238476172;8487.448315859448;8496.745097647081;8529.189726975927;6270.655113968775;6370.58591191571;6395;6401.8719625608155;6414.262359828679;7108.182559005573;7113.502054856111;7158.964205178941;7165.390941770008;7182.138143422822;6344.365563443804;6360.077788720517;6384.680765961776;6416.839579010562;6458.644774537445;6431.389018043278;6492.35076492347;6492.740290388331;6500.934990650033;6522.21733391996;5478.397376288525;5578.135389014709;5603.971980140073;5613.382527922007;5617.297837770278;4233.449651545279;4257.2549803783595;4258.6805989550285;4305.052888364555;4380.0582874682095;4153.979230103831;4212.7472351659;4234.9576504234565;4353.368761529624;4409.80155892992 14:29:33 INFO - PROCESS | 15630 | |4;prototype.html;323.5357262328559;345.37314773685756;347.3053892215569;347.9565054368204;353.98230088495575;4997.40031196256;5097.1589687701535;5151.974240128799;5159.896802063966;5164.612654050947;5002.17464118421;5013.598912087034;5048;5064.847439838063;5069.605858388945;3925.9018524536827;3931.7247792654452;3943.7830919299427;3950.802459877009;3963.702722295825;3709.925801483969;3984.681225501967;3994.980025099885;4003.219372222418;4005.8798236052967;3860.6332398422246;3903.160820423608;3904.0239940014994;3910;3915.804209789513;3901.6683581895572;3905.7656540607654;3908.1011367385377;3908.491896053509;3919.902002449933;3398.558187435642;3413.8805141820167;3431.1765176357744;3432.2792213635166;3434.9484757728655;4709;4926.531979461981;4986.326845875797;5001.299818025479;5028.918782461788;4401.163778882034;4439.66702497314;4446;4447.177272204653;4454.153710794971;5293.470652934714;5325.467453254682;5333.973090225728;5347.59893008022;5390.1914712793005;3625.329314076878;3637.872674456408;3668.523091998023;3736.327461057009;3773.2642134783728;5408.701911541241;5528;5541.639793413456;5601.971990140064;5612.803551875665;3800.6769424598665;3829.6744776693736;3836.2519308734527;3843.7693738375788;3869.7097717671286;3880.185161116141;3889.397143442771;3905.9218815624095;3908.413737939332;3923.7645741255615;4198.6221240088535;4469.173202957463;4508.864734057984;4517;4521.1409832131785;5319.111715341076;5346.732663366816;5361.222622719683;5364.895854671179;5375.569954403638;1302.742853146718;1312.5471712259255;1317.8286822713178;1323.9007074469453;1324.635725175565;817.4686453805074;864.5893200729603;890.8663700444985;903.4624398482893;909.5770466733001;4538.868180955131;4540.367705835319;4554.749488778083;4565.726056436624;4574.176648203289;5392.91910621341;5400.405955344909;5404.486573775485;5407.486288802636;5407.594430417735;4380.69335146543;4517.6160026397465;4575.565321294472;4583.335416364608;4590.862274131781;2802.621646077794;2848.473032488996;2848.61543691599;2848.9715102849123;2852.9001484947926;5012.598992080625;5027.748612569357;5040.092783298968;5062.974685126551;5069.087564238399;5348.5988550858065;5437.782488700408;5508.44915508446;5553.139263414178;5554.805581804618;4968.354113965162;4986.8753281168265;4987.900241995212;5005.224190250517;5035.874103147451;5295.258663786996;5299.867503312448;5309.442508536548;5352.635078055071;5369.3019907413345;5000.849974500771;5237.031149237403;5238.135707608202;5246.767009752629;5277.20841873722;4986.775595098156;5006.474320196473;5101.464346243591;5123.233453934833;5155.896882062413;3843.5003449551377;3853.788041657736;3856.093817952835;3859.710521710827;3898.298306304779;5501.889962200814;5542.782886845294;5567.331920169606;5580;5582.441755824385;5214.869628259324;5250.369955605199;5301.814436494704;5336.626436149432;5360.276362691143;5309.65487243328;5317.946820531745;5330.893382132258;5343.251944727664;5358.973205134105;5119.74401279927;5268.420473747808;5274.97362513185;5284.180951952453;5286.7356632169;5335.546478549367;5348.224507446476;5354.330708661418;5358.892822143457;5371.946280537145;5223.503767142115;5255;5259.947400526099;5270.657407268515;5290.497402746733 14:29:33 INFO - PROCESS | 15630 | |5;yui.html;618.6628287583267;639.4820195641528;708.7058870568715;725.5211560370155;744.7914583916502;12790.424430900608;13090.280034598094;13167.6708082298;13209.14140580864;13410.798838017425;12745.107842451032;12842.550510732126;12935.353232338393;13065.477380904753;13100.934495327534;807.5275963561323;822.5517093184229;825.2160447574798;826.182079741058;826.557791581504;797.1948332184506;813.8494378539959;826.7602395305354;835.3192148399036;837.8521425646842;864.5849992003842;866.1252135343317;866.9783255418602;869.256785448442;870.268974061788;866.0603245478663;866.3026263857593;866.4238281542798;868;870.6125774030559;628.2649300318639;633.5944995203074;634.5050860328951;635.1552435261092;636.8025911967305;13539.526116585872;13816.240106794123;13828.446862125504;13962.301884905815;14014;12473.376331183495;12895.548655797002;12956.935215323863;13085.018623603268;13238.470461181541;10147.34042287249;10426.739331516697;10434.113100386476;10440.269181157322;10549.367037977747;3205.3909757146303;3222.9033129006166;3319.867205311785;3354.781939173946;3366.932661346784;43648.3452748209;43780.56219437797;44535.331970020474;50494.24258636124;54437.91124177534;9918.603255869684;9936.205103591696;10033.64882229133;10145.239107067;10275.537600807982;18287.54281142982;18373;18375.72436413455;18435.354762583243;18838;6217.96891015542;6284.842878927972;6298.716557754912;6314.0844577536;6435.388638079375;3171.476706343474;3283.359744849779;3329.1011426914597;3347.313800670857;3428.4171690812623;2620.8034397419888;2665.560182569854;2669.7997650176317;2684.154491335223;2689.1394753678637;452.579101435668;472.86286976777035;479.302614695617;484.706752414791;485.3229744506394;553.5405613340918;563.1349495009512;576.4063015094459;581.8225441240378;586.8474196708801;7518.323350898446;7692.576908270099;7791.590076944738;7888.211178882066;8030.919690803017;554.7531348549937;586.9413058694182;602.0157043234343;607.419913982146;609.817054883542;3276.262840860787;3308.4871844864083;3312.337532493511;3333.21669407689;3349.1627093226693;62124;63359.36640633535;63445.36554634487;64698.35301647017;65259;42743.35884961728;50459.49540504621;51919.85065339141;53855;56048;47690.61876247505;48653.75673121612;48857.26714099292;49111;50502;6484.902726459012;6508.674566271573;6650.933490665031;6742.42689371409;6828.283030281749;33114.834425827714;33488.64647595797;33543;33742;35092.29815403627;39222.41166382564;39645.80177099096;41989;42394.78802606091;43205;8105.75682729519;8153.755387338152;8336.166383361615;8355.498670079814;8447.8732819009;9684.418934863648;9697.563609637442;9705.272104592043;9737.756556086155;9830.901690982999;8738.781530461789;8751.868721969047;8818.559072046244;9279.443233405747;9284.675036373694;8278.751637450887;8325.333973282002;8463.830723385374;8713.128687131237;8807.339449541181;8923.73228803137;8953;9002.81994360096;9046.683366082156;9134.862977055482;7639.770806875803;8046.316063134465;8203.179682031749;8275.379346548913;8421.536815475207;7091.1490621125795;7160.749373771893;7535.13345965221;7651.311381975649;8010.679572817256 14:29:33 INFO - PROCESS | 15630 | __end_tp_report 14:29:33 INFO - PROCESS | 15630 | __start_cc_report 14:29:33 INFO - PROCESS | 15630 | _x_x_mozilla_cycle_collect,377 14:29:33 INFO - PROCESS | 15630 | __end_cc_report 14:29:33 INFO - PROCESS | 15630 | __startTimestamp1487370573592__endTimestamp 14:29:33 INFO - PROCESS | 15630 | 14:29:33 INFO - PROCESS | 15630 | ------- Summary: start ------- 14:29:33 INFO - PROCESS | 15630 | Number of tests: 6 14:29:33 INFO - PROCESS | 15630 | 14:29:33 INFO - PROCESS | 15630 | [#0] dojo.html Cycles:165 Average:4810.09 Median:4918.34 stddev:504.38 (10.3%) stddev-sans-first:475.67 14:29:33 INFO - PROCESS | 15630 | Values: 2616.4 2617.1 2618.3 2645.0 2665.0 4903.2 4912.3 4918.3 4944.0 4961.0 4889.6 4931.4 4951.3 4994.3 4998.0 4269.4 4493.8 4572.2 4621.7 4631.0 4560.7 4587.7 4591.5 4609.0 4633.4 4319.1 4346.9 4446.1 4497.4 4523.7 4466.6 4473.9 4490.2 4544.8 4562.1 4430.6 4455.3 4472.0 4472.2 4502.8 4719.2 4747.5 4875.0 4906.1 4918.3 5130.5 5143.4 5165.8 5171.3 5200.0 5158.3 5181.5 5188.4 5192.7 5234.3 4791.8 4856.2 4861.3 4865.0 4866.1 5236.4 5310.7 5316.9 5325.3 5326.1 5005.1 5017.8 5029.3 5060.7 5098.6 5029.7 5043.7 5049.1 5054.6 5071.1 4896.7 5058.1 5097.0 5098.7 5117.2 4900.2 5146.9 5149.9 5171.1 5269.9 4447.2 4455.2 4455.3 4466.8 4517.2 3856.1 3857.0 3858.3 3893.8 3909.1 4396.0 4435.2 4535.8 4574.4 4575.8 5269.7 5319.1 5329.3 5329.8 5359.6 4580.0 4586.2 4600.3 4615.4 4625.7 4602.6 4640.0 4643.7 4669.8 4827.0 4814.5 4848.9 4856.6 4860.7 4865.2 5157.8 5170.1 5183.5 5192.0 5207.6 4865.6 4867.0 4890.5 4912.9 4915.8 5052.0 5119.6 5129.1 5131.4 5136.1 5087.6 5146.9 5213.9 5221.4 5250.6 5082.0 5158.8 5164.3 5192.4 5207.2 5113.3 5132.8 5143.8 5147.9 5195.4 4839.3 4871.6 5035.4 5059.8 5093.9 5090.7 5125.9 5128.9 5134.7 5216.3 4983.7 4986.2 4994.6 5008.9 5035.4 14:29:33 INFO - PROCESS | 15630 | 14:29:33 INFO - PROCESS | 15630 | [#1] ext.html Cycles:180 Average:42455.86 Median:36409.32 stddev:71641.08 (196.8%) stddev-sans-first:71772.88 14:29:33 INFO - PROCESS | 15630 | Values: 526.3 538.6 538.9 553.2 591.4 34029.5 38202.2 38252.2 38257.6 38328.2 37916.0 38512.8 38543.2 38615.4 38615.6 7539.7 7710.3 7719.9 9237.0 9620.8 9371.2 9462.1 9466.8 9473.0 9481.6 9413.4 9467.0 9468.6 9485.7 9490.1 9391.6 9422.5 9462.7 9470.4 9473.9 9339.2 9361.3 9390.2 9413.6 9433.0 32471.7 32622.7 32702.0 32785.8 32867.2 3299.8 3379.3 3415.3 3454.5 3465.2 25258.4 25320.1 25403.1 25542.4 25597.5 3419.2 3455.4 3580.7 3593.8 3612.1 213394.0 214078.0 214233.5 215264.9 215504.0 38422.6 39557.2 45645.5 45681.3 46076.0 44553.0 48075.0 48259.5 48396.5 48415.3 12532.0 12582.9 12826.6 12876.0 12894.9 8978.9 9058.5 9266.4 9304.3 9381.8 15534.5 15604.2 15618.4 15663.3 15730.7 6285.9 6343.4 6525.0 6544.4 6600.9 742.8 772.5 772.7 795.1 799.5 25562.1 25852.1 26628.2 26903.9 27094.3 887.7 926.5 930.6 946.9 953.3 13806.7 14086.6 14316.9 14357.9 14486.7 326096.0 327947.0 329194.0 329908.0 330487.0 47686.8 48134.8 48154.3 48263.3 48323.0 1133.3 1176.0 1192.7 1192.9 1194.4 24361.3 25165.2 26438.7 26476.2 26487.5 39909.0 40266.4 40284.0 40331.2 40483.4 264646.0 266261.0 266684.0 267316.0 267626.0 24165.0 34957.3 38296.8 38447.6 41330.4 37380.0 38835.6 40448.8 40930.6 41756.6 35781.5 35986.6 36188.8 36629.8 36980.8 34379.8 34439.8 34934.8 35153.3 35514.1 34238.7 35011.8 35550.8 35752.8 35903.0 31137.5 32000.7 32572.5 32646.0 32913.5 24082.3 24489.0 24664.5 25102.1 25467.4 14:29:33 INFO - PROCESS | 15630 | 14:29:33 INFO - PROCESS | 15630 | [#2] jquery.html Cycles:180 Average:24790.34 Median:4586.08 stddev:65380.00 (1425.6%) stddev-sans-first:65537.72 14:29:33 INFO - PROCESS | 15630 | Values: 381.8 394.8 395.1 397.2 417.4 12576.3 13264.9 15422.7 18019.1 18097.7 16267.9 16803.6 17260.4 18212.8 18440.5 4877.8 5141.8 5177.9 5298.5 5328.1 5104.0 5201.1 5212.0 5214.1 5225.5 4976.1 5118.5 5121.8 5168.8 5175.7 5094.3 5162.6 5178.3 5183.4 5187.2 4496.4 4562.5 4609.6 4611.6 4613.5 21366.8 21459.7 21652.0 21702.6 21712.3 2553.1 2591.8 2620.9 2655.6 2656.3 13404.8 13459.8 13605.1 13694.2 14310.6 1716.2 1722.6 1726.2 1738.9 1740.8 77604.0 77749.2 77860.2 78824.6 78973.0 10135.5 10322.4 10323.9 10357.8 10525.7 12081.5 14484.1 14513.7 14518.6 14541.5 6725.6 6819.9 6878.7 7020.0 7152.7 6499.6 6513.9 6579.3 6730.0 6759.7 4032.6 4095.0 4121.6 4153.2 4178.9 1932.7 1971.9 2015.2 2021.7 2039.6 922.4 955.9 959.4 959.4 961.2 16622.7 17224.8 17727.1 17769.5 17808.6 918.2 921.8 939.6 945.0 946.1 9401.4 9437.3 9668.1 9684.4 9766.0 319162.0 327595.0 328826.0 468674.0 484651.0 86371.6 88927.0 89475.6 90151.5 90667.0 921.3 922.2 974.6 975.5 981.1 15759.1 15880.4 16414.5 16421.9 16864.2 29179.4 33388.7 34999.7 37801.8 39128.2 47933.5 48001.0 49346.5 49432.5 50969.5 7454.9 7669.2 7739.5 7796.4 7851.1 11225.2 11846.3 12279.9 12424.3 12546.2 9693.9 9768.8 10070.9 10126.8 10201.7 9919.6 10046.3 10087.3 10145.4 10172.2 9608.1 9627.6 10031.6 10042.5 10261.9 7628.2 7671.8 7708.4 7732.2 7906.1 9028.3 9577.0 9654.3 9785.4 10054.7 14:29:33 INFO - PROCESS | 15630 | 14:29:33 INFO - PROCESS | 15630 | [#3] mootools.html Cycles:180 Average:4821.90 Median:5114.43 stddev:2679.07 (52.4%) stddev-sans-first:2673.58 14:29:33 INFO - PROCESS | 15630 | Values: 1309.7 1345.4 1346.7 1353.0 1356.9 7189.2 7481.0 7556.1 7569.7 7687.3 7614.0 7774.0 7774.5 7778.6 7816.1 2073.7 2099.5 2106.1 2109.2 2111.2 2051.4 2089.8 2092.5 2096.8 2098.0 2052.9 2059.1 2073.1 2082.0 2084.7 2016.5 2018.8 2029.8 2036.2 2045.5 1724.1 1776.7 1997.9 2009.9 2090.8 7213.8 7451.3 7510.6 7654.2 7715.4 4288.2 4402.3 4405.5 4419.9 4435.4 2701.4 2703.1 2738.6 2765.7 2772.3 999.4 1003.7 1007.4 1078.7 1105.9 10056.8 10157.2 10215.2 10292.8 10300.6 3660.9 3778.6 3846.7 3875.2 3895.3 8034.7 8043.5 8064.9 8065.8 8075.5 4371.7 4391.2 4401.4 4409.8 4416.6 3907.5 4053.5 4193.7 4196.4 4216.1 3184.8 3233.1 3310.7 3314.2 3318.9 973.4 976.3 981.3 994.6 995.7 736.6 751.9 766.8 775.5 787.0 4476.9 4868.4 4876.4 4982.3 5122.7 847.7 882.1 884.6 885.6 887.8 3226.7 3289.7 3311.5 3354.6 3397.8 7876.6 8098.9 8191.6 8618.4 9099.8 8296.5 8515.3 8522.7 8531.8 8534.5 7086.1 7147.3 7172.6 7278.2 7322.5 4970.9 5000.8 5106.1 5181.7 5202.2 8010.6 8308.1 8347.5 8383.4 8394.0 8409.5 8476.2 8487.4 8496.7 8529.2 6270.7 6370.6 6395.0 6401.9 6414.3 7108.2 7113.5 7159.0 7165.4 7182.1 6344.4 6360.1 6384.7 6416.8 6458.6 6431.4 6492.4 6492.7 6500.9 6522.2 5478.4 5578.1 5604.0 5613.4 5617.3 4233.4 4257.3 4258.7 4305.1 4380.1 4154.0 4212.7 4235.0 4353.4 4409.8 14:29:33 INFO - PROCESS | 15630 | 14:29:33 INFO - PROCESS | 15630 | [#4] prototype.html Cycles:180 Average:4378.35 Median:5003.70 stddev:1280.59 (25.6%) stddev-sans-first:1247.49 14:29:33 INFO - PROCESS | 15630 | Values: 323.5 345.4 347.3 348.0 354.0 4997.4 5097.2 5152.0 5159.9 5164.6 5002.2 5013.6 5048.0 5064.8 5069.6 3925.9 3931.7 3943.8 3950.8 3963.7 3709.9 3984.7 3995.0 4003.2 4005.9 3860.6 3903.2 3904.0 3910.0 3915.8 3901.7 3905.8 3908.1 3908.5 3919.9 3398.6 3413.9 3431.2 3432.3 3434.9 4709.0 4926.5 4986.3 5001.3 5028.9 4401.2 4439.7 4446.0 4447.2 4454.2 5293.5 5325.5 5334.0 5347.6 5390.2 3625.3 3637.9 3668.5 3736.3 3773.3 5408.7 5528.0 5541.6 5602.0 5612.8 3800.7 3829.7 3836.3 3843.8 3869.7 3880.2 3889.4 3905.9 3908.4 3923.8 4198.6 4469.2 4508.9 4517.0 4521.1 5319.1 5346.7 5361.2 5364.9 5375.6 1302.7 1312.5 1317.8 1323.9 1324.6 817.5 864.6 890.9 903.5 909.6 4538.9 4540.4 4554.7 4565.7 4574.2 5392.9 5400.4 5404.5 5407.5 5407.6 4380.7 4517.6 4575.6 4583.3 4590.9 2802.6 2848.5 2848.6 2849.0 2852.9 5012.6 5027.7 5040.1 5063.0 5069.1 5348.6 5437.8 5508.4 5553.1 5554.8 4968.4 4986.9 4987.9 5005.2 5035.9 5295.3 5299.9 5309.4 5352.6 5369.3 5000.8 5237.0 5238.1 5246.8 5277.2 4986.8 5006.5 5101.5 5123.2 5155.9 3843.5 3853.8 3856.1 3859.7 3898.3 5501.9 5542.8 5567.3 5580.0 5582.4 5214.9 5250.4 5301.8 5336.6 5360.3 5309.7 5317.9 5330.9 5343.3 5359.0 5119.7 5268.4 5275.0 5284.2 5286.7 5335.5 5348.2 5354.3 5358.9 5371.9 5223.5 5255.0 5259.9 5270.7 5290.5 14:29:33 INFO - PROCESS | 15630 | 14:29:33 INFO - PROCESS | 15630 | [#5] yui.html Cycles:180 Average:13271.00 Median:604.72 stddev:16562.41 (2738.9%) stddev-sans-first:16581.62 14:29:33 INFO - PROCESS | 15630 | Values: 618.7 639.5 708.7 725.5 744.8 12790.4 13090.3 13167.7 13209.1 13410.8 12745.1 12842.6 12935.4 13065.5 13100.9 807.5 822.6 825.2 826.2 826.6 797.2 813.8 826.8 835.3 837.9 864.6 866.1 867.0 869.3 870.3 866.1 866.3 866.4 868.0 870.6 628.3 633.6 634.5 635.2 636.8 13539.5 13816.2 13828.4 13962.3 14014.0 12473.4 12895.5 12956.9 13085.0 13238.5 10147.3 10426.7 10434.1 10440.3 10549.4 3205.4 3222.9 3319.9 3354.8 3366.9 43648.3 43780.6 44535.3 50494.2 54437.9 9918.6 9936.2 10033.6 10145.2 10275.5 18287.5 18373.0 18375.7 18435.4 18838.0 6218.0 6284.8 6298.7 6314.1 6435.4 3171.5 3283.4 3329.1 3347.3 3428.4 2620.8 2665.6 2669.8 2684.2 2689.1 452.6 472.9 479.3 484.7 485.3 553.5 563.1 576.4 581.8 586.8 7518.3 7692.6 7791.6 7888.2 8030.9 554.8 586.9 602.0 607.4 609.8 3276.3 3308.5 3312.3 3333.2 3349.2 62124.0 63359.4 63445.4 64698.4 65259.0 42743.4 50459.5 51919.9 53855.0 56048.0 47690.6 48653.8 48857.3 49111.0 50502.0 6484.9 6508.7 6650.9 6742.4 6828.3 33114.8 33488.6 33543.0 33742.0 35092.3 39222.4 39645.8 41989.0 42394.8 43205.0 8105.8 8153.8 8336.2 8355.5 8447.9 9684.4 9697.6 9705.3 9737.8 9830.9 8738.8 8751.9 8818.6 9279.4 9284.7 8278.8 8325.3 8463.8 8713.1 8807.3 8923.7 8953.0 9002.8 9046.7 9134.9 7639.8 8046.3 8203.2 8275.4 8421.5 7091.1 7160.7 7535.1 7651.3 8010.7 14:29:33 INFO - PROCESS | 15630 | -------- Summary: end -------- 14:29:33 INFO - PROCESS | 15630 | 14:29:34 INFO - TEST-INFO | 15630: exit 0 14:29:34 INFO - TEST-OK | dromaeo_css | took 1120926ms 14:29:34 INFO - TEST-START | kraken 14:29:34 INFO - operating with platform_type : linux_ 14:29:34 INFO - Initialising browser for kraken test... 14:29:34 INFO - TEST-INFO | started process 16003 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl8gR9h/profile http://localhost:41234/getInfo.html) 14:29:37 INFO - PROCESS | 16003 | Iterating over profile /tmp/tmpl8gR9h/profile 14:29:38 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:38 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/key3.db = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:38 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:38 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/mimeTypes.rdf = Fri Feb 17 2017 14:29:38 GMT-0800 (PST) 14:29:38 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:38 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/places.sqlite = Fri Feb 17 2017 14:29:38 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/content-prefs.sqlite = Fri Feb 17 2017 14:29:36 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/secmod.db = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | Stat failure 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/thumbnails = Fri Feb 17 2017 14:29:37 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/frequencyCap.json = Fri Feb 17 2017 14:29:37 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | Stat failure 14:29:39 INFO - PROCESS | 16003 | __metrics Screen width/height:1600/1200 14:29:39 INFO - PROCESS | 16003 | colorDepth:24 14:29:39 INFO - PROCESS | 16003 | Browser inner width/height: 1024/697 14:29:39 INFO - PROCESS | 16003 | __metrics 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/addons.json = Fri Feb 17 2017 14:29:37 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/bookmarkbackups = Fri Feb 17 2017 14:29:37 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/blocklist.xml = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/compatibility.ini = Fri Feb 17 2017 14:29:35 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/user.js = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/permissions.sqlite = Fri Feb 17 2017 14:29:35 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/places.sqlite-wal = Fri Feb 17 2017 14:29:39 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/extensions.json = Fri Feb 17 2017 14:29:37 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/pluginreg.dat = Fri Feb 17 2017 14:29:37 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/startupCache = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/prefs.js = Fri Feb 17 2017 14:10:12 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/localstore.rdf = Fri Feb 17 2017 14:10:12 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/webappsstore.sqlite = Fri Feb 17 2017 14:29:38 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/directoryLinks.json = Fri Feb 17 2017 14:29:37 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/crashes = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/extensions = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/storage.sqlite = Fri Feb 17 2017 14:29:38 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/extensions.ini = Fri Feb 17 2017 14:29:35 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/gmp = Fri Feb 17 2017 14:29:35 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/minidumps = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/cache2 = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/places.sqlite-shm = Fri Feb 17 2017 14:29:36 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/.parentlock = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/safebrowsing = Fri Feb 17 2017 14:29:39 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/cert8.db = Fri Feb 17 2017 14:29:34 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | No birth date. Using mtime. 14:29:39 INFO - PROCESS | 16003 | Using date: /tmp/tmpl8gR9h/profile/sessionCheckpoints.json = Fri Feb 17 2017 14:29:38 GMT-0800 (PST) 14:29:39 INFO - PROCESS | 16003 | Stat failure 14:29:40 ERROR - PROCESS | 16003 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 14:29:41 INFO - TEST-INFO | 16003: exit 0 14:29:41 INFO - Browser initialized. 14:29:41 INFO - Running cycle 1/1 for kraken test... 14:29:41 INFO - TEST-INFO | started process 16130 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl8gR9h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:29:53 INFO - PROCESS | 16130 | RSS: Main: 156491776 14:29:53 INFO - PROCESS | 16130 | 14:29:55 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41234/tests/kraken/driver.html?testName=audio-beat-detection) 14:29:56 INFO - PROCESS | 16130 | RSS: Main: 162017280 14:29:56 INFO - PROCESS | 16130 | 14:29:59 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41234/tests/kraken/driver.html?testName=audio-dft) 14:30:00 INFO - PROCESS | 16130 | RSS: Main: 160464896 14:30:00 INFO - PROCESS | 16130 | 14:30:03 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41234/tests/kraken/driver.html?testName=audio-fft) 14:30:03 INFO - PROCESS | 16130 | RSS: Main: 155336704 14:30:03 INFO - PROCESS | 16130 | 14:30:06 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41234/tests/kraken/driver.html?testName=audio-oscillator) 14:30:06 INFO - PROCESS | 16130 | RSS: Main: 155947008 14:30:06 INFO - PROCESS | 16130 | 14:30:08 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:30:09 INFO - PROCESS | 16130 | RSS: Main: 156131328 14:30:09 INFO - PROCESS | 16130 | 14:30:12 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41234/tests/kraken/driver.html?testName=imaging-darkroom) 14:30:13 INFO - PROCESS | 16130 | RSS: Main: 161480704 14:30:13 INFO - PROCESS | 16130 | 14:30:16 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41234/tests/kraken/driver.html?testName=imaging-desaturate) 14:30:17 INFO - PROCESS | 16130 | RSS: Main: 157728768 14:30:17 INFO - PROCESS | 16130 | 14:30:20 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41234/tests/kraken/driver.html?testName=json-parse-financial) 14:30:21 INFO - PROCESS | 16130 | RSS: Main: 158285824 14:30:21 INFO - PROCESS | 16130 | 14:30:22 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:30:23 INFO - PROCESS | 16130 | RSS: Main: 158900224 14:30:23 INFO - PROCESS | 16130 | 14:30:25 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41234/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:30:25 INFO - PROCESS | 16130 | RSS: Main: 159043584 14:30:25 INFO - PROCESS | 16130 | 14:30:28 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:30:28 INFO - PROCESS | 16130 | RSS: Main: 159076352 14:30:28 INFO - PROCESS | 16130 | 14:30:31 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:30:32 INFO - PROCESS | 16130 | RSS: Main: 159502336 14:30:32 INFO - PROCESS | 16130 | 14:30:35 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:30:36 INFO - PROCESS | 16130 | RSS: Main: 162873344 14:30:36 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:41234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41234/tests/kraken/driver.html?testName=ai-astar) 14:30:38 INFO - PROCESS | 16130 | RSS: Main: 163590144 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | __start_tp_report 14:30:38 INFO - PROCESS | 16130 | _x_x_mozilla_page_load 14:30:38 INFO - PROCESS | 16130 | _x_x_mozilla_page_load_details 14:30:38 INFO - PROCESS | 16130 | |i|pagename|runs| 14:30:38 INFO - PROCESS | 16130 | |0;ai-astar;109;115;118;117;116;117;116;116;118;117 14:30:38 INFO - PROCESS | 16130 | |1;audio-beat-detection;154;129;132;175;127;128;129;130;151;133 14:30:38 INFO - PROCESS | 16130 | |2;audio-dft;164;168;168;168;184;164;163;165;164;165 14:30:38 INFO - PROCESS | 16130 | |3;audio-fft;78;78;76;111;80;77;77;77;93;78 14:30:38 INFO - PROCESS | 16130 | |4;audio-oscillator;85;88;109;89;88;86;99;85;84;112 14:30:38 INFO - PROCESS | 16130 | |5;imaging-gaussian-blur;115;115;115;114;114;116;114;115;114;114 14:30:38 INFO - PROCESS | 16130 | |6;imaging-darkroom;169;150;153;151;150;151;150;153;150;150 14:30:38 INFO - PROCESS | 16130 | |7;imaging-desaturate;95;96;96;96;96;96;96;98;96;97 14:30:38 INFO - PROCESS | 16130 | |8;json-parse-financial;65;65;64;64;68;67;67;66;66;67 14:30:38 INFO - PROCESS | 16130 | |9;json-stringify-tinderbox;65;66;65;66;66;66;66;65;65;66 14:30:38 INFO - PROCESS | 16130 | |10;stanford-crypto-aes;90;88;95;94;91;91;88;88;88;86 14:30:38 INFO - PROCESS | 16130 | |11;stanford-crypto-ccm;145;142;145;149;139;146;138;140;184;138 14:30:38 INFO - PROCESS | 16130 | |12;stanford-crypto-pbkdf2;183;190;185;188;185;189;188;180;189;188 14:30:38 INFO - PROCESS | 16130 | |13;stanford-crypto-sha256-iterative;71;71;72;73;71;73;71;72;71;76 14:30:38 INFO - PROCESS | 16130 | __end_tp_report 14:30:38 INFO - PROCESS | 16130 | __start_cc_report 14:30:38 INFO - PROCESS | 16130 | _x_x_mozilla_cycle_collect,885 14:30:38 INFO - PROCESS | 16130 | __end_cc_report 14:30:38 INFO - PROCESS | 16130 | __startTimestamp1487370638301__endTimestamp 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | ------- Summary: start ------- 14:30:38 INFO - PROCESS | 16130 | Number of tests: 14 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#0] ai-astar Cycles:10 Average:115.90 Median:117.00 stddev:2.60 (2.2%) stddev-sans-first:1.00 14:30:38 INFO - PROCESS | 16130 | Values: 109.0 115.0 118.0 117.0 116.0 117.0 116.0 116.0 118.0 117.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:132.50 stddev:15.97 (12.1%) stddev-sans-first:15.96 14:30:38 INFO - PROCESS | 16130 | Values: 154.0 129.0 132.0 175.0 127.0 128.0 129.0 130.0 151.0 133.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#2] audio-dft Cycles:10 Average:167.30 Median:166.50 stddev:6.17 (3.7%) stddev-sans-first:6.42 14:30:38 INFO - PROCESS | 16130 | Values: 164.0 168.0 168.0 168.0 184.0 164.0 163.0 165.0 164.0 165.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#3] audio-fft Cycles:10 Average:82.50 Median:78.00 stddev:11.17 (14.3%) stddev-sans-first:11.73 14:30:38 INFO - PROCESS | 16130 | Values: 78.0 78.0 76.0 111.0 80.0 77.0 77.0 77.0 93.0 78.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:87.00 stddev:10.41 (12.0%) stddev-sans-first:10.68 14:30:38 INFO - PROCESS | 16130 | Values: 85.0 88.0 109.0 89.0 88.0 86.0 99.0 85.0 84.0 112.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:115.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 14:30:38 INFO - PROCESS | 16130 | Values: 115.0 115.0 115.0 114.0 114.0 116.0 114.0 115.0 114.0 114.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#6] imaging-darkroom Cycles:10 Average:152.70 Median:151.00 stddev:5.85 (3.9%) stddev-sans-first:1.27 14:30:38 INFO - PROCESS | 16130 | Values: 169.0 150.0 153.0 151.0 150.0 151.0 150.0 153.0 150.0 150.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.71 14:30:38 INFO - PROCESS | 16130 | Values: 95.0 96.0 96.0 96.0 96.0 96.0 96.0 98.0 96.0 97.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:66.50 stddev:1.37 (2.1%) stddev-sans-first:1.41 14:30:38 INFO - PROCESS | 16130 | Values: 65.0 65.0 64.0 64.0 68.0 67.0 67.0 66.0 66.0 67.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 14:30:38 INFO - PROCESS | 16130 | Values: 65.0 66.0 65.0 66.0 66.0 66.0 66.0 65.0 65.0 66.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:90.50 stddev:2.88 (3.2%) stddev-sans-first:3.06 14:30:38 INFO - PROCESS | 16130 | Values: 90.0 88.0 95.0 94.0 91.0 91.0 88.0 88.0 88.0 86.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:145.00 stddev:13.66 (9.4%) stddev-sans-first:14.48 14:30:38 INFO - PROCESS | 16130 | Values: 145.0 142.0 145.0 149.0 139.0 146.0 138.0 140.0 184.0 138.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:188.00 stddev:3.17 (1.7%) stddev-sans-first:3.10 14:30:38 INFO - PROCESS | 16130 | Values: 183.0 190.0 185.0 188.0 185.0 189.0 188.0 180.0 189.0 188.0 14:30:38 INFO - PROCESS | 16130 | 14:30:38 INFO - PROCESS | 16130 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:72.00 stddev:1.60 (2.2%) stddev-sans-first:1.64 14:30:38 INFO - PROCESS | 16130 | Values: 71.0 71.0 72.0 73.0 71.0 73.0 71.0 72.0 71.0 76.0 14:30:38 INFO - PROCESS | 16130 | -------- Summary: end -------- 14:30:38 INFO - PROCESS | 16130 | 14:30:39 INFO - TEST-INFO | 16130: exit 0 14:30:39 INFO - TEST-OK | kraken | took 65004ms 14:30:39 INFO - SUITE-END | took 1185s 14:30:39 INFO - Completed test suite (00:19:46) 14:30:39 INFO - javascript benchmark 14:30:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6271.037223523588, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2616.42438663494, 2617.097101499983, 2618.3061488705507, 2644.986775066125, 2664.9999999999986, 4903.19097348937, 4912.312276281324, 4918.336024636673, 4944.0, 4960.950390496094, 4889.559939605444, 4931.358923339961, 4951.331570238017, 4994.275830004647, 4997.975010124945, 4269.423627810237, 4493.842715504966, 4572.154151481986, 4621.745803980796, 4631.0, 4560.680752347337, 4587.747673877935, 4591.494935557086, 4609.03210325832, 4633.397658304432, 4319.136172765459, 4346.9347959780625, 4446.110777844444, 4497.392851965008, 4523.660725445605, 4466.598006179427, 4473.932891006638, 4490.169318676056, 4544.840930567448, 4562.133194692998, 4430.556944305576, 4455.331700244957, 4472.016156445577, 4472.239719247736, 4502.797374118172, 4719.174144524694, 4747.501512341189, 4874.975625121887, 4906.141425250603, 4918.286848406961, 5130.538251557377, 5143.408508021551, 5165.793368265265, 5171.250168725552, 5200.037992971313, 5158.2520534522655, 5181.533661970403, 5188.429272779991, 5192.7403629818355, 5234.26720259164, 4791.808327666924, 4856.17445034345, 4861.27080937855, 4865.026994601094, 4866.075445665348, 5236.371635403776, 5310.707911064928, 5316.893662126736, 5325.307709997753, 5326.121190003683, 5005.074061298671, 5017.774200161001, 5029.27075574047, 5060.671056381323, 5098.643094983316, 5029.673071250357, 5043.697378157322, 5049.141645920203, 5054.620903432257, 5071.061853557104, 4896.681715688469, 5058.1148299048255, 5096.974515127475, 5098.719570423662, 5117.2068329409, 4900.215965445512, 5146.948530514722, 5149.922751158736, 5171.095058364846, 5269.866978599543, 4447.199504089231, 4455.1757924784015, 4455.309427038814, 4466.798994045275, 4517.209488339593, 3856.0745421099296, 3856.9779008562755, 3858.3247931611854, 3893.785841778673, 3909.1204478992568, 4395.978020109879, 4435.223835828717, 4535.841245556389, 4574.359589657451, 4575.794089265924, 5269.710165940834, 5319.148936170194, 5329.280547126088, 5329.786808527616, 5359.624826262202, 4580.038191979722, 4586.220342541709, 4600.309953506934, 4615.446146462379, 4625.676202665781, 4602.631789456836, 4639.9536004640195, 4643.6517261205545, 4669.813207471663, 4827.0, 4814.470408255159, 4848.927266090941, 4856.587190088882, 4860.684055536379, 4865.221564549796, 5157.7679004444135, 5170.0952333340765, 5183.455737147545, 5192.013517431676, 5207.635465517529, 4865.635077369141, 4866.95133048665, 4890.462049174517, 4912.926306105483, 4915.8033678652455, 5052.0, 5119.6416250862085, 5129.051125541787, 5131.358580177478, 5136.1268584340005, 5087.618428617942, 5146.8970620588125, 5213.921791173211, 5221.373435187802, 5250.579953603779, 5081.983603279433, 5158.793648254028, 5164.32863727728, 5192.3769147702515, 5207.2449494823795, 5113.284140220298, 5132.820351287687, 5143.819966301311, 5147.948520514747, 5195.350581177353, 4839.322494850653, 4871.561559459666, 5035.420926593489, 5059.822906198266, 5093.898122037613, 5090.745462726923, 5125.923111153261, 5128.871778205575, 5134.743262836918, 5216.295800067094, 4983.70097794119, 4986.202207646759, 4994.5754610858485, 5008.949910500848, 5035.370578677665], "value": 4777.052177523339, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.3263023330136, 538.6229639252523, 538.9137737961927, 553.1978630985072, 591.4469970577513, 34029.489557656656, 38202.23595528088, 38252.23495530095, 38257.61742382575, 38328.233435331276, 37916.01881802406, 38512.80743596278, 38543.22913541728, 38615.42076868842, 38615.61384386155, 7539.736109236178, 7710.306072453478, 7719.922800772005, 9237.0, 9620.807583848318, 9371.156595906366, 9462.053794620551, 9466.763330916749, 9472.952635236848, 9481.573329200202, 9413.388129771578, 9467.0, 9468.573914173845, 9485.715428537154, 9490.05099490049, 9391.577379017961, 9422.481763503005, 9462.71611851642, 9470.384425012353, 9473.857892131555, 9339.206167475773, 9361.344705870591, 9390.154886060287, 9413.57638906251, 9433.0, 32471.675283247103, 32622.67377326244, 32702.0, 32785.83607081973, 32867.17832054194, 3299.7525185610934, 3379.3410284994434, 3415.316936612687, 3454.4990976308545, 3465.1683595937047, 25258.368540786443, 25320.113796017235, 25403.110891118722, 25542.361440964123, 25597.488050239077, 3419.230673098558, 3455.3953058214965, 3580.6777390034763, 3593.802340871251, 3612.0608641753333, 213394.0, 214078.0, 214233.5018274353, 215264.9236753822, 215504.0, 38422.615773841906, 39557.20885582272, 45645.543544564796, 45681.31478027832, 46076.0, 44553.0, 48075.0, 48259.517404826205, 48396.5160348392, 48415.273770893466, 12532.0, 12582.874171258354, 12826.55107071248, 12876.0, 12894.871051289554, 8978.86531702025, 9058.456492610332, 9266.351355405057, 9304.25565954722, 9381.812363752822, 15534.533963981099, 15604.21978901073, 15618.3752649895, 15663.295151717972, 15730.68538629221, 6285.937140628626, 6343.365663433619, 6525.0, 6544.44372228366, 6600.900986485207, 742.7548908860064, 772.4631381190019, 772.6754762999553, 795.0737390939606, 799.4843326054778, 25562.10532631349, 25852.095176668725, 26628.201153965412, 26903.86548067247, 27094.322641933715, 887.7070566712969, 926.4533924984291, 930.5626355612851, 946.9005754395827, 953.3088510829732, 13806.723865522834, 14086.577402677936, 14316.864806738597, 14357.928210358881, 14486.710265794625, 326096.0, 327947.0, 329194.0, 329908.0, 330487.0, 47686.76156619195, 48134.759326203144, 48154.27768583474, 48263.27605085926, 48323.0, 1133.331334512625, 1175.9647210583696, 1192.7316353820322, 1192.8986036186848, 1194.378922960056, 24361.26916192517, 25165.205394543736, 26438.735612644396, 26476.205713828615, 26487.470250595266, 39909.0, 40266.396004059374, 40284.0, 40331.19337613173, 40483.36858104214, 264646.0, 266261.0, 266684.0, 267316.0, 267626.0, 24165.0, 34957.26068482879, 38296.808515957244, 38447.615523844404, 41330.38004429996, 37380.0, 38835.611643883196, 40448.79775601103, 40930.590694093866, 41756.58243417648, 35781.46327805137, 35986.64013359833, 36188.819055904554, 36629.81685091557, 36980.81509592435, 34379.82810085933, 34439.82780086083, 34934.82532587321, 35153.296934061684, 35514.11214719652, 34238.657613423544, 35011.82494087513, 35550.82224588861, 35752.82123589469, 35903.0, 31137.53293700551, 32000.67999319977, 32572.51141232836, 32646.0, 32913.54407787148, 24082.277531674077, 24489.0, 24664.506709866062, 25102.11734119912, 25467.36331591725], "value": 16987.637540315656, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.77475289579127, 394.8144372145092, 395.05581659833024, 397.24919901386386, 417.3760228458455, 12576.308303043328, 13264.867351326508, 15422.691546169084, 18019.099045047744, 18097.728534072, 16267.918660406682, 16803.579910502245, 17260.395886143986, 18212.817871821277, 18440.53898652531, 4877.804887804493, 5141.794328226866, 5177.948220517803, 5298.463445600791, 5328.120860058086, 5103.974480127594, 5201.115810312237, 5212.0, 5214.061468935609, 5225.529702326789, 4976.079425306312, 5118.462561431052, 5121.795128194868, 5168.844934651947, 5175.689458632494, 5094.337736094302, 5162.586993040585, 5178.291163915898, 5183.429822719517, 5187.221916712504, 4496.437945256843, 4562.543745625444, 4609.608183304423, 4611.608013318872, 4613.515580864028, 21366.786332136635, 21459.67810482844, 21652.0, 21702.574356410896, 21712.34862954114, 2553.093651753638, 2591.7926565874686, 2620.882060307291, 2655.6016597510334, 2656.3491944473535, 13404.798928016087, 13459.798103028463, 13605.115667481681, 13694.178349298974, 14310.64223394413, 1716.1933891071176, 1722.6382459683482, 1726.231826837058, 1738.939137130207, 1740.782402199725, 77604.0, 77749.22250777476, 77860.22139778586, 78824.60587697082, 78973.0, 10135.493225338703, 10322.432266225429, 10323.94838025805, 10357.844632330522, 10525.684229473129, 12081.45633446497, 14484.130952142905, 14513.709725805636, 14518.564443066725, 14541.491047813275, 6725.6212476442215, 6819.863602727918, 6878.65606719662, 7020.0, 7152.749653762094, 6499.610023398611, 6513.869722605521, 6579.342065793382, 6729.966350168218, 6759.662016899136, 4032.5967403260024, 4094.959050409517, 4121.5878412159145, 4153.210889930903, 4178.937315940263, 1932.748742663473, 1971.9309824156373, 2015.1637070615823, 2021.6563184258712, 2039.6124736300353, 922.3773952582114, 955.9474228917476, 959.3764053365229, 959.4243453927727, 961.1925982174866, 16622.66754664924, 17224.827751722572, 17727.11364431773, 17769.46691599254, 17808.643827123386, 918.150710592699, 921.801657844799, 939.5771902643837, 945.0, 946.0728486083676, 9401.388909720847, 9437.292203084797, 9668.129868311886, 9684.370515916444, 9765.951170244103, 319162.0, 327595.0, 328826.0, 468674.0, 484651.0, 86371.56814216015, 88927.0, 89475.55262223647, 90151.54924225337, 90667.0, 921.3090182363228, 922.1838672774509, 974.6198982396871, 975.5463709375033, 981.0581841432305, 15759.133247671489, 15880.364785408916, 16414.507564773077, 16421.91789041047, 16864.15679216059, 29179.416411671224, 33388.66611333856, 34999.65000350066, 37801.81099094597, 39128.2174356517, 47933.52066479291, 48001.0, 49346.50653493419, 49432.50567494423, 50969.490305097956, 7454.92545074564, 7669.23307669251, 7739.458237923292, 7796.415268854745, 7851.136374998631, 11225.214235003472, 11846.289222646668, 12279.877201227873, 12424.254544727346, 12546.1844980076, 9693.854592181264, 9768.755781105529, 10070.899291006996, 10126.848097278695, 10201.74495637615, 9919.553620087258, 10046.296759226783, 10087.344322619007, 10145.391276523433, 10172.186225102123, 9608.087231712976, 9627.614895404105, 10031.648892288735, 10042.49787510636, 10261.9486902565, 7628.160902300853, 7671.846563068819, 7708.421868359784, 7732.188120247518, 7906.130325664058, 9028.27773778083, 9576.952115239379, 9654.275929305471, 9785.412875227225, 10054.69835904924], "value": 8397.603447128258, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.731505041466, 1345.3676771917198, 1346.6767975685839, 1352.9526466573666, 1356.905016648835, 7189.173245076818, 7480.962595187017, 7556.093268807744, 7569.697212111516, 7687.346575541072, 7614.0, 7773.961130194355, 7774.455788094836, 7778.611069446533, 7816.140224575292, 2073.688946658002, 2099.496120930973, 2106.083853523722, 2109.219588752164, 2111.1660893946882, 2051.4471335342814, 2089.8432617553744, 2092.4559614500263, 2096.821770149539, 2098.0453893478507, 2052.9486762830898, 2059.1454546363243, 2073.139647046474, 2082.0422605601443, 2084.687296905461, 2016.4656366063004, 2018.8082132197417, 2029.8071683190074, 2036.216056818121, 2045.4681782736448, 1724.1379310344828, 1776.7423723560141, 1997.9001049947444, 2009.9095540700703, 2090.8118269355678, 7213.819654508627, 7451.2921272479025, 7510.586917719524, 7654.234576542357, 7715.382769378436, 4288.206681763883, 4402.273624851896, 4405.493368262659, 4419.889502762425, 4435.401220835178, 2701.405690748032, 2703.1079743684536, 2738.6165936768866, 2765.6542932133484, 2772.2930652683435, 999.3504222255519, 1003.6537394598852, 1007.4358359318732, 1078.6710053433699, 1105.8728246251703, 10056.849147262797, 10157.187425005981, 10215.182785377152, 10292.845607315896, 10300.587976480932, 3660.890173294752, 3778.6221377862544, 3846.673032792188, 3875.2443273561385, 3895.2988462077096, 8034.678612855537, 8043.477173983672, 8064.879026814602, 8065.798355041054, 8075.515469071875, 4371.715838470489, 4391.165678521071, 4401.449818772653, 4409.845655402046, 4416.580424859633, 3907.4724912137067, 4053.533843608023, 4193.727407718488, 4196.391523229115, 4216.114615930689, 3184.792988455743, 3233.11089450403, 3310.668933106718, 3314.171457135716, 3318.867245310209, 973.4013581647235, 976.2970661123979, 981.3326937682444, 994.5822754443006, 995.7411073121041, 736.5838301359714, 751.9248075192546, 766.7853001159684, 775.4571799740091, 787.00836945448, 4476.910461790746, 4868.440129385095, 4876.366072410564, 4982.327385802942, 5122.743862806845, 847.7075408983891, 882.1178821178821, 884.6372987075268, 885.6324625280565, 887.7780554861284, 3226.6773322667545, 3289.73682105431, 3311.4536101542976, 3354.630990591033, 3397.8470968806464, 7876.606169691492, 8098.878516822252, 8191.631376588068, 8618.353623478264, 9099.772505687411, 8296.460730052528, 8515.276201522816, 8522.701705440404, 8531.829363412697, 8534.487930724177, 7086.078809754802, 7147.3210045045635, 7172.641367931583, 7278.235785242685, 7322.487425880244, 4970.850874473772, 5000.849974500771, 5106.106431374569, 5181.715005674724, 5202.193659982709, 8010.599470026592, 8308.129250853237, 8347.54088525137, 8383.371247156365, 8394.034686010942, 8409.53747543891, 8476.15238476172, 8487.448315859448, 8496.745097647081, 8529.189726975927, 6270.655113968775, 6370.58591191571, 6395.0, 6401.8719625608155, 6414.262359828679, 7108.182559005573, 7113.502054856111, 7158.964205178941, 7165.390941770008, 7182.138143422822, 6344.365563443804, 6360.077788720517, 6384.680765961776, 6416.839579010562, 6458.644774537445, 6431.389018043278, 6492.35076492347, 6492.740290388331, 6500.934990650033, 6522.21733391996, 5478.397376288525, 5578.135389014709, 5603.971980140073, 5613.382527922007, 5617.297837770278, 4233.449651545279, 4257.2549803783595, 4258.6805989550285, 4305.052888364555, 4380.0582874682095, 4153.979230103831, 4212.7472351659, 4234.9576504234565, 4353.368761529624, 4409.80155892992], "value": 3894.6647417588047, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.5357262328559, 345.37314773685756, 347.3053892215569, 347.9565054368204, 353.98230088495575, 4997.40031196256, 5097.1589687701535, 5151.974240128799, 5159.896802063966, 5164.612654050947, 5002.17464118421, 5013.598912087034, 5048.0, 5064.847439838063, 5069.605858388945, 3925.9018524536827, 3931.7247792654452, 3943.7830919299427, 3950.802459877009, 3963.702722295825, 3709.925801483969, 3984.681225501967, 3994.980025099885, 4003.219372222418, 4005.8798236052967, 3860.6332398422246, 3903.160820423608, 3904.0239940014994, 3910.0, 3915.804209789513, 3901.6683581895572, 3905.7656540607654, 3908.1011367385377, 3908.491896053509, 3919.902002449933, 3398.558187435642, 3413.8805141820167, 3431.1765176357744, 3432.2792213635166, 3434.9484757728655, 4709.0, 4926.531979461981, 4986.326845875797, 5001.299818025479, 5028.918782461788, 4401.163778882034, 4439.66702497314, 4446.0, 4447.177272204653, 4454.153710794971, 5293.470652934714, 5325.467453254682, 5333.973090225728, 5347.59893008022, 5390.1914712793005, 3625.329314076878, 3637.872674456408, 3668.523091998023, 3736.327461057009, 3773.2642134783728, 5408.701911541241, 5528.0, 5541.639793413456, 5601.971990140064, 5612.803551875665, 3800.6769424598665, 3829.6744776693736, 3836.2519308734527, 3843.7693738375788, 3869.7097717671286, 3880.185161116141, 3889.397143442771, 3905.9218815624095, 3908.413737939332, 3923.7645741255615, 4198.6221240088535, 4469.173202957463, 4508.864734057984, 4517.0, 4521.1409832131785, 5319.111715341076, 5346.732663366816, 5361.222622719683, 5364.895854671179, 5375.569954403638, 1302.742853146718, 1312.5471712259255, 1317.8286822713178, 1323.9007074469453, 1324.635725175565, 817.4686453805074, 864.5893200729603, 890.8663700444985, 903.4624398482893, 909.5770466733001, 4538.868180955131, 4540.367705835319, 4554.749488778083, 4565.726056436624, 4574.176648203289, 5392.91910621341, 5400.405955344909, 5404.486573775485, 5407.486288802636, 5407.594430417735, 4380.69335146543, 4517.6160026397465, 4575.565321294472, 4583.335416364608, 4590.862274131781, 2802.621646077794, 2848.473032488996, 2848.61543691599, 2848.9715102849123, 2852.9001484947926, 5012.598992080625, 5027.748612569357, 5040.092783298968, 5062.974685126551, 5069.087564238399, 5348.5988550858065, 5437.782488700408, 5508.44915508446, 5553.139263414178, 5554.805581804618, 4968.354113965162, 4986.8753281168265, 4987.900241995212, 5005.224190250517, 5035.874103147451, 5295.258663786996, 5299.867503312448, 5309.442508536548, 5352.635078055071, 5369.3019907413345, 5000.849974500771, 5237.031149237403, 5238.135707608202, 5246.767009752629, 5277.20841873722, 4986.775595098156, 5006.474320196473, 5101.464346243591, 5123.233453934833, 5155.896882062413, 3843.5003449551377, 3853.788041657736, 3856.093817952835, 3859.710521710827, 3898.298306304779, 5501.889962200814, 5542.782886845294, 5567.331920169606, 5580.0, 5582.441755824385, 5214.869628259324, 5250.369955605199, 5301.814436494704, 5336.626436149432, 5360.276362691143, 5309.65487243328, 5317.946820531745, 5330.893382132258, 5343.251944727664, 5358.973205134105, 5119.74401279927, 5268.420473747808, 5274.97362513185, 5284.180951952453, 5286.7356632169, 5335.546478549367, 5348.224507446476, 5354.330708661418, 5358.892822143457, 5371.946280537145, 5223.503767142115, 5255.0, 5259.947400526099, 5270.657407268515, 5290.497402746733], "value": 3978.2071454117727, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.6628287583267, 639.4820195641528, 708.7058870568715, 725.5211560370155, 744.7914583916502, 12790.424430900608, 13090.280034598094, 13167.6708082298, 13209.14140580864, 13410.798838017425, 12745.107842451032, 12842.550510732126, 12935.353232338393, 13065.477380904753, 13100.934495327534, 807.5275963561323, 822.5517093184229, 825.2160447574798, 826.182079741058, 826.557791581504, 797.1948332184506, 813.8494378539959, 826.7602395305354, 835.3192148399036, 837.8521425646842, 864.5849992003842, 866.1252135343317, 866.9783255418602, 869.256785448442, 870.268974061788, 866.0603245478663, 866.3026263857593, 866.4238281542798, 868.0, 870.6125774030559, 628.2649300318639, 633.5944995203074, 634.5050860328951, 635.1552435261092, 636.8025911967305, 13539.526116585872, 13816.240106794123, 13828.446862125504, 13962.301884905815, 14014.0, 12473.376331183495, 12895.548655797002, 12956.935215323863, 13085.018623603268, 13238.470461181541, 10147.34042287249, 10426.739331516697, 10434.113100386476, 10440.269181157322, 10549.367037977747, 3205.3909757146303, 3222.9033129006166, 3319.867205311785, 3354.781939173946, 3366.932661346784, 43648.3452748209, 43780.56219437797, 44535.331970020474, 50494.24258636124, 54437.91124177534, 9918.603255869684, 9936.205103591696, 10033.64882229133, 10145.239107067, 10275.537600807982, 18287.54281142982, 18373.0, 18375.72436413455, 18435.354762583243, 18838.0, 6217.96891015542, 6284.842878927972, 6298.716557754912, 6314.0844577536, 6435.388638079375, 3171.476706343474, 3283.359744849779, 3329.1011426914597, 3347.313800670857, 3428.4171690812623, 2620.8034397419888, 2665.560182569854, 2669.7997650176317, 2684.154491335223, 2689.1394753678637, 452.579101435668, 472.86286976777035, 479.302614695617, 484.706752414791, 485.3229744506394, 553.5405613340918, 563.1349495009512, 576.4063015094459, 581.8225441240378, 586.8474196708801, 7518.323350898446, 7692.576908270099, 7791.590076944738, 7888.211178882066, 8030.919690803017, 554.7531348549937, 586.9413058694182, 602.0157043234343, 607.419913982146, 609.817054883542, 3276.262840860787, 3308.4871844864083, 3312.337532493511, 3333.21669407689, 3349.1627093226693, 62124.0, 63359.36640633535, 63445.36554634487, 64698.35301647017, 65259.0, 42743.35884961728, 50459.49540504621, 51919.85065339141, 53855.0, 56048.0, 47690.61876247505, 48653.75673121612, 48857.26714099292, 49111.0, 50502.0, 6484.902726459012, 6508.674566271573, 6650.933490665031, 6742.42689371409, 6828.283030281749, 33114.834425827714, 33488.64647595797, 33543.0, 33742.0, 35092.29815403627, 39222.41166382564, 39645.80177099096, 41989.0, 42394.78802606091, 43205.0, 8105.75682729519, 8153.755387338152, 8336.166383361615, 8355.498670079814, 8447.8732819009, 9684.418934863648, 9697.563609637442, 9705.272104592043, 9737.756556086155, 9830.901690982999, 8738.781530461789, 8751.868721969047, 8818.559072046244, 9279.443233405747, 9284.675036373694, 8278.751637450887, 8325.333973282002, 8463.830723385374, 8713.128687131237, 8807.339449541181, 8923.73228803137, 8953.0, 9002.81994360096, 9046.683366082156, 9134.862977055482, 7639.770806875803, 8046.316063134465, 8203.179682031749, 8275.379346548913, 8421.536815475207, 7091.1490621125795, 7160.749373771893, 7535.13345965221, 7651.311381975649, 8010.679572817256], "value": 5759.459477595889, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 115.0, 118.0, 117.0, 116.0, 117.0, 116.0, 116.0, 118.0, 117.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 129.0, 132.0, 175.0, 127.0, 128.0, 129.0, 130.0, 151.0, 133.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 168.0, 168.0, 184.0, 164.0, 163.0, 165.0, 164.0, 165.0], "value": 167.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 76.0, 111.0, 80.0, 77.0, 77.0, 77.0, 93.0, 78.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 109.0, 89.0, 88.0, 86.0, 99.0, 85.0, 84.0, 112.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 114.0, 114.0, 116.0, 114.0, 115.0, 114.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 150.0, 153.0, 151.0, 150.0, 151.0, 150.0, 153.0, 150.0, 150.0], "value": 152.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 98.0, 96.0, 97.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 64.0, 68.0, 67.0, 67.0, 66.0, 66.0, 67.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 95.0, 94.0, 91.0, 91.0, 88.0, 88.0, 88.0, 86.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 142.0, 145.0, 149.0, 139.0, 146.0, 138.0, 140.0, 184.0, 138.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 190.0, 185.0, 188.0, 185.0, 189.0, 188.0, 180.0, 189.0, 188.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 72.0, 73.0, 71.0, 73.0, 71.0, 72.0, 71.0, 76.0], "value": 72.1, "unit": "score"}]}]} 14:30:40 INFO - Return code: 0 14:30:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:30:40 ERROR - # TBPL SUCCESS # 14:30:40 INFO - Running post-action listener: _resource_record_post_action 14:30:40 INFO - [mozharness: 2017-02-17 22:30:40.085944Z] Finished run-tests step (success) 14:30:40 INFO - Running post-run listener: _resource_record_post_run 14:30:40 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 0; Write bytes: 359477248; Read time: 0; Write time: 250880 14:30:40 INFO - TinderboxPrint: CPU usage
13.5% 14:30:40 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:30:40 INFO - TinderboxPrint: I/O write bytes / time
359,477,248 / 250,880 14:30:40 INFO - TinderboxPrint: CPU idle
8,295.8 (86.5%) 14:30:40 INFO - TinderboxPrint: CPU user
1,239.1 (12.9%) 14:30:40 INFO - TinderboxPrint: Swap in / out
0 / 0 14:30:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105287680; Read time: 0; Write time: 98024 14:30:40 INFO - run-tests - Wall time: 1187s; CPU: 14.0%; Read bytes: 0; Write bytes: 254189568; Read time: 0; Write time: 152856 14:30:40 INFO - Running post-run listener: _upload_blobber_files 14:30:40 INFO - Blob upload gear active. 14:30:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:30:40 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 14:30:40 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'] 14:30:40 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 14:30:41 INFO - (blobuploader) - INFO - Open directory for files ... 14:30:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:30:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:30:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:41 INFO - (blobuploader) - INFO - Done attempting. 14:30:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:30:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:30:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:42 INFO - (blobuploader) - INFO - Done attempting. 14:30:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:30:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:30:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:46 INFO - (blobuploader) - INFO - Done attempting. 14:30:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:30:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:30:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:47 INFO - (blobuploader) - INFO - Done attempting. 14:30:47 INFO - (blobuploader) - INFO - Iteration through files over. 14:30:47 INFO - Return code: 0 14:30:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:30:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:30:47 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9956669fa31db4a8cfa614236387e43a458f4f15f86d476adcfcb304f274b5ceeca92e4c087d9de8f199cf6e745c17d980caa7b48db3cb871a0ff14e43d2f3c7", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/51bad926d69bb8ef11cab6dc1f00b15ae0dd899eec85d65af7c8a22e58364a3d79ad3341b8ba8d76dd586616d49adc19ab3d1babea785ae49ecfe7edea09a5a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/473266a8cfd9134729b9167f70cc5df23766c81b653c505b0cb6963cc9654627903dc8e35424ad61327907acd2f44dce1d255952cc015da04d0c5473c4ba6ae3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37b4afe85fa2b9b88de9d2337e3c53bdae9a2025e46f93b0745ac9150d1694195e08f4199d189bea03dff0944bf3a2c5dda8bd5c126217ac5acf60a854bda3f3"} 14:30:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:30:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:30:47 INFO - Contents: 14:30:47 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9956669fa31db4a8cfa614236387e43a458f4f15f86d476adcfcb304f274b5ceeca92e4c087d9de8f199cf6e745c17d980caa7b48db3cb871a0ff14e43d2f3c7", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/51bad926d69bb8ef11cab6dc1f00b15ae0dd899eec85d65af7c8a22e58364a3d79ad3341b8ba8d76dd586616d49adc19ab3d1babea785ae49ecfe7edea09a5a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/473266a8cfd9134729b9167f70cc5df23766c81b653c505b0cb6963cc9654627903dc8e35424ad61327907acd2f44dce1d255952cc015da04d0c5473c4ba6ae3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37b4afe85fa2b9b88de9d2337e3c53bdae9a2025e46f93b0745ac9150d1694195e08f4199d189bea03dff0944bf3a2c5dda8bd5c126217ac5acf60a854bda3f3"} 14:30:47 INFO - Running post-run listener: copy_logs_to_upload_dir 14:30:47 INFO - Copying logs to upload dir... 14:30:47 INFO - mkdir: /builds/slave/test/build/upload/logs 14:30:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.710261 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-17 14:30:46.483554) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 14:30:46.488939) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9956669fa31db4a8cfa614236387e43a458f4f15f86d476adcfcb304f274b5ceeca92e4c087d9de8f199cf6e745c17d980caa7b48db3cb871a0ff14e43d2f3c7", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/51bad926d69bb8ef11cab6dc1f00b15ae0dd899eec85d65af7c8a22e58364a3d79ad3341b8ba8d76dd586616d49adc19ab3d1babea785ae49ecfe7edea09a5a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/473266a8cfd9134729b9167f70cc5df23766c81b653c505b0cb6963cc9654627903dc8e35424ad61327907acd2f44dce1d255952cc015da04d0c5473c4ba6ae3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37b4afe85fa2b9b88de9d2337e3c53bdae9a2025e46f93b0745ac9150d1694195e08f4199d189bea03dff0944bf3a2c5dda8bd5c126217ac5acf60a854bda3f3"} build_url:https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011389 build_url: 'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9956669fa31db4a8cfa614236387e43a458f4f15f86d476adcfcb304f274b5ceeca92e4c087d9de8f199cf6e745c17d980caa7b48db3cb871a0ff14e43d2f3c7", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/51bad926d69bb8ef11cab6dc1f00b15ae0dd899eec85d65af7c8a22e58364a3d79ad3341b8ba8d76dd586616d49adc19ab3d1babea785ae49ecfe7edea09a5a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/473266a8cfd9134729b9167f70cc5df23766c81b653c505b0cb6963cc9654627903dc8e35424ad61327907acd2f44dce1d255952cc015da04d0c5473c4ba6ae3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37b4afe85fa2b9b88de9d2337e3c53bdae9a2025e46f93b0745ac9150d1694195e08f4199d189bea03dff0944bf3a2c5dda8bd5c126217ac5acf60a854bda3f3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 14:30:46.515141) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:30:46.515564) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1487369379.752220-1941616542 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:30:46.599592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 14:30:46.600002) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 14:30:46.600340) ========= ========= Total master_lag: 0.17 =========