builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-029 starttime: 1487823688.41 results: success (0) revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.415223) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.415673) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.416044) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.439298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.439532) ========= 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=ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-22 20:21:28-- 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% 137M=0s 2017-02-22 20:21:29 (137 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.195722 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.648102) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.648380) ========= 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=ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018157 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.697133) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 20:21:28.697435) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c7b015c488cfb2afbcff295a9639acd85df332f8 --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 c7b015c488cfb2afbcff295a9639acd85df332f8 --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=ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-22 20:21:29,227 truncating revision to first 12 chars 2017-02-22 20:21:29,227 Setting DEBUG logging. 2017-02-22 20:21:29,227 attempt 1/10 2017-02-22 20:21:29,227 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c7b015c488cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-22 20:21:30,112 unpacking tar archive at: mozilla-central-c7b015c488cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.254643 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 20:21:29.965134) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.965477) ========= script_repo_revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.965916) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.966211) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.976295) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-02-22 20:21:29.976629) ========= /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=ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:21:30 INFO - MultiFileLogger online at 20170222 20:21:30 in /builds/slave/test 20:21:30 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 20:21:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:21:30 INFO - {'append_to_log': False, 20:21:30 INFO - 'base_work_dir': '/builds/slave/test', 20:21:30 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:21:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:21:30 INFO - 'branch': 'Firefox-Non-PGO', 20:21:30 INFO - 'buildbot_json_path': 'buildprops.json', 20:21:30 INFO - 'config_files': ('talos/linux_config.py',), 20:21:30 INFO - 'default_actions': ('clobber', 20:21:30 INFO - 'read-buildbot-config', 20:21:30 INFO - 'download-and-extract', 20:21:30 INFO - 'populate-webroot', 20:21:30 INFO - 'create-virtualenv', 20:21:30 INFO - 'install', 20:21:30 INFO - 'run-tests'), 20:21:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:21:30 INFO - 'download_minidump_stackwalk': True, 20:21:30 INFO - 'download_symbols': 'ondemand', 20:21:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:21:30 INFO - 'tooltool.py': '/tools/tooltool.py', 20:21:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:21:30 INFO - '/tools/misc-python/virtualenv.py')}, 20:21:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:21:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:21:30 INFO - 'gecko_profile': False, 20:21:30 INFO - 'gecko_profile_interval': 0, 20:21:30 INFO - 'installer_path': 'installer.exe', 20:21:30 INFO - 'log_level': 'info', 20:21:30 INFO - 'log_name': 'talos', 20:21:30 INFO - 'log_to_console': True, 20:21:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:21:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:21:30 INFO - 'opt_config_files': (), 20:21:30 INFO - 'pip_index': False, 20:21:30 INFO - 'suite': 'dromaeojs-e10s', 20:21:30 INFO - 'system_bits': '32', 20:21:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:21:30 INFO - 'title': 'talos-linux64-ix-029', 20:21:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:21:30 INFO - 'use_talos_json': True, 20:21:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:21:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:21:30 INFO - 'work_dir': 'build'} 20:21:30 INFO - [mozharness: 2017-02-23 04:21:30.609916Z] Running clobber step. 20:21:30 INFO - Running pre-action listener: _resource_record_pre_action 20:21:30 INFO - Running main action method: clobber 20:21:30 INFO - rmtree: /builds/slave/test/build 20:21:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:21:31 INFO - Running post-action listener: _resource_record_post_action 20:21:31 INFO - [mozharness: 2017-02-23 04:21:31.531346Z] Finished clobber step (success) 20:21:31 INFO - [mozharness: 2017-02-23 04:21:31.531448Z] Running read-buildbot-config step. 20:21:31 INFO - Running pre-action listener: _resource_record_pre_action 20:21:31 INFO - Running main action method: read_buildbot_config 20:21:31 INFO - Using buildbot properties: 20:21:31 INFO - { 20:21:31 INFO - "project": "", 20:21:31 INFO - "product": "firefox", 20:21:31 INFO - "who": "kwierso@gmail.com", 20:21:31 INFO - "installer_path": "public/build/target.tar.bz2", 20:21:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:21:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:21:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 20:21:31 INFO - "stage_platform": "linux64", 20:21:31 INFO - "basedir": "/builds/slave/test", 20:21:31 INFO - "buildnumber": 720, 20:21:31 INFO - "platform": "ubuntu64_hw", 20:21:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:21:31 INFO - "slavebuilddir": "test", 20:21:31 INFO - "taskId": "GrqYadKmTFeg40rrWXdsDg", 20:21:31 INFO - "branch": "mozilla-central", 20:21:31 INFO - "script_repo_revision": "production", 20:21:31 INFO - "revision": "c7b015c488cfb2afbcff295a9639acd85df332f8", 20:21:31 INFO - "slavename": "talos-linux64-ix-029", 20:21:31 INFO - "repo_path": "mozilla-central" 20:21:31 INFO - } 20:21:31 INFO - Finding installer, test and symbols from parent task. 20:21:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:32 INFO - Task dependencies: fziZoBLZQf6J64GfmjMy5g 20:21:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g'}, attempt #1 20:21:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:21:32 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/test_packages.json 20:21:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip 20:21:32 INFO - Running post-action listener: _resource_record_post_action 20:21:32 INFO - [mozharness: 2017-02-23 04:21:32.959293Z] Finished read-buildbot-config step (success) 20:21:32 INFO - [mozharness: 2017-02-23 04:21:32.959555Z] Running download-and-extract step. 20:21:32 INFO - Running pre-action listener: _resource_record_pre_action 20:21:32 INFO - Running main action method: download_and_extract 20:21:32 INFO - mkdir: /builds/slave/test/build/tests 20:21:32 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')]} 20:21:32 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json 20:21:32 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:21:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:21:33 INFO - Downloaded 896 bytes. 20:21:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:21:33 INFO - Using the following test package requirements: 20:21:33 INFO - {u'common': [u'target.common.tests.zip'], 20:21:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:21:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:21:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:21:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:21:33 INFO - u'mozbase': [u'target.common.tests.zip'], 20:21:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:21:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:21:33 INFO - u'web-platform': [u'target.common.tests.zip', 20:21:33 INFO - u'target.web-platform.tests.zip'], 20:21:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:21:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:21:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 20:21:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:21:33 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:21:37 INFO - Expected file size: 57165889 20:21:37 INFO - Obtained file size: 57165889 20:21:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:21:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 20:21:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:21:47 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:21:49 INFO - Expected file size: 57165889 20:21:49 INFO - Obtained file size: 57165889 20:21:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip 20:21:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:21:59 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip into memory 20:22:00 INFO - Expected file size: 14404319 20:22:00 INFO - Obtained file size: 14404319 20:22:00 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')]} 20:22:00 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:00 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:22:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:22:02 INFO - Downloaded 61547669 bytes. 20:22:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:02 INFO - mkdir: /builds/slave/test/properties 20:22:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:22:02 INFO - Writing to file /builds/slave/test/properties/build_url 20:22:02 INFO - Contents: 20:22:02 INFO - build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:02 INFO - Running post-action listener: _resource_record_post_action 20:22:02 INFO - Running post-action listener: set_extra_try_arguments 20:22:02 INFO - [mozharness: 2017-02-23 04:22:02.599925Z] Finished download-and-extract step (success) 20:22:02 INFO - [mozharness: 2017-02-23 04:22:02.600150Z] Running populate-webroot step. 20:22:02 INFO - Running pre-action listener: _resource_record_pre_action 20:22:02 INFO - Running main action method: populate_webroot 20:22:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:22:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:22:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:22:02 INFO - '--tppagecycles', 20:22:02 INFO - '7'], 20:22:02 INFO - 'tests': ['tsvgm']}}, 20:22:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:22:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:22:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:22:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:22:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:22:02 INFO - 'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:22:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:22:02 INFO - 'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['damp', 'tps']}, 20:22:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:22:02 INFO - 'tests': ['damp', 'tps']}, 20:22:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['dromaeo_dom']}, 20:22:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:22:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['basic_compositor_video']}, 20:22:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:22:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['a11yr', 20:22:02 INFO - 'ts_paint', 20:22:02 INFO - 'tpaint', 20:22:02 INFO - 'sessionrestore', 20:22:02 INFO - 'sessionrestore_no_auto_restore', 20:22:02 INFO - 'tabpaint']}, 20:22:02 INFO - 'other-e10s': {'tests': ['a11yr', 20:22:02 INFO - 'ts_paint', 20:22:02 INFO - 'tpaint', 20:22:02 INFO - 'sessionrestore', 20:22:02 INFO - 'sessionrestore_no_auto_restore', 20:22:02 INFO - 'tabpaint']}, 20:22:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:22:02 INFO - 'ts_paint', 20:22:02 INFO - 'tpaint', 20:22:02 INFO - 'sessionrestore', 20:22:02 INFO - 'sessionrestore_no_auto_restore', 20:22:02 INFO - 'tabpaint']}, 20:22:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:22:02 INFO - 'ts_paint', 20:22:02 INFO - 'tpaint', 20:22:02 INFO - 'sessionrestore', 20:22:02 INFO - 'sessionrestore_no_auto_restore', 20:22:02 INFO - 'tabpaint']}, 20:22:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['a11yr', 20:22:02 INFO - 'ts_paint', 20:22:02 INFO - 'tpaint', 20:22:02 INFO - 'sessionrestore', 20:22:02 INFO - 'sessionrestore_no_auto_restore', 20:22:02 INFO - 'tabpaint']}, 20:22:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['a11yr', 20:22:02 INFO - 'ts_paint', 20:22:02 INFO - 'tpaint', 20:22:02 INFO - 'sessionrestore', 20:22:02 INFO - 'sessionrestore_no_auto_restore', 20:22:02 INFO - 'tabpaint']}, 20:22:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['tsvgx', 20:22:02 INFO - 'tsvgr_opacity', 20:22:02 INFO - 'tart', 20:22:02 INFO - 'tscrollx', 20:22:02 INFO - 'cart', 20:22:02 INFO - 'tsvg_static']}, 20:22:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:22:02 INFO - 'tsvgr_opacity', 20:22:02 INFO - 'tart', 20:22:02 INFO - 'tscrollx', 20:22:02 INFO - 'cart', 20:22:02 INFO - 'tsvg_static']}, 20:22:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:22:02 INFO - 'talos_options': ['--disable-e10s'], 20:22:02 INFO - 'tests': ['tp5o']}, 20:22:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:22:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:22:02 INFO - 'talos_options': ['--disable-e10s', 20:22:02 INFO - '--xperf_path', 20:22:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:02 INFO - 'tests': ['tp5n']}, 20:22:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:22:02 INFO - 'talos_options': ['--xperf_path', 20:22:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:02 INFO - 'tests': ['tp5n']}}, 20:22:02 INFO - 'talos.zip': {'path': '', 20:22:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:22:02 INFO - Running post-action listener: _resource_record_post_action 20:22:02 INFO - [mozharness: 2017-02-23 04:22:02.620343Z] Finished populate-webroot step (success) 20:22:02 INFO - [mozharness: 2017-02-23 04:22:02.620433Z] Running create-virtualenv step. 20:22:02 INFO - Running pre-action listener: _resource_record_pre_action 20:22:02 INFO - Running main action method: create_virtualenv 20:22:02 INFO - Creating virtualenv /builds/slave/test/build/venv 20:22:02 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 20:22:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:22:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:22:02 INFO - Using real prefix '/usr' 20:22:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:22:03 INFO - Installing distribute.............................................................................................................................................................................................done. 20:22:05 INFO - Installing pip.................done. 20:22:05 INFO - Return code: 0 20:22:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:22:05 INFO - Reading from file tmpfile_stdout 20:22:05 INFO - Output received: 20:22:05 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) 20:22:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:22:05 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')]} 20:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:05 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')]} 20:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x195e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', '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 20:22:05 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 20:22:05 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 20:22:05 INFO - Using env: {'DISPLAY': ':0', 20:22:05 INFO - 'HOME': '/home/cltbld', 20:22:05 INFO - 'LANG': 'en_US.UTF-8', 20:22:05 INFO - 'LANGUAGE': 'en_US:en', 20:22:05 INFO - 'LOGNAME': 'cltbld', 20:22:05 INFO - 'MAIL': '/var/mail/cltbld', 20:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:05 INFO - 'MOZ_NO_REMOTE': '1', 20:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:05 INFO - 'NO_EM_RESTART': '1', 20:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:05 INFO - 'PWD': '/builds/slave/test', 20:22:05 INFO - 'SHELL': '/bin/bash', 20:22:05 INFO - 'SHLVL': '1', 20:22:05 INFO - 'TERM': 'linux', 20:22:05 INFO - 'TMOUT': '86400', 20:22:05 INFO - 'USER': 'cltbld', 20:22:05 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:05 INFO - '_': '/tools/buildbot/bin/python'} 20:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:05 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 20:22:05 INFO - Cleaning up... 20:22:05 INFO - Return code: 0 20:22:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:22:05 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')]} 20:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:05 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')]} 20:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x195e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', '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 20:22:05 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 20:22:05 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 20:22:05 INFO - Using env: {'DISPLAY': ':0', 20:22:05 INFO - 'HOME': '/home/cltbld', 20:22:05 INFO - 'LANG': 'en_US.UTF-8', 20:22:05 INFO - 'LANGUAGE': 'en_US:en', 20:22:05 INFO - 'LOGNAME': 'cltbld', 20:22:05 INFO - 'MAIL': '/var/mail/cltbld', 20:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:05 INFO - 'MOZ_NO_REMOTE': '1', 20:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:05 INFO - 'NO_EM_RESTART': '1', 20:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:05 INFO - 'PWD': '/builds/slave/test', 20:22:05 INFO - 'SHELL': '/bin/bash', 20:22:05 INFO - 'SHLVL': '1', 20:22:05 INFO - 'TERM': 'linux', 20:22:05 INFO - 'TMOUT': '86400', 20:22:05 INFO - 'USER': 'cltbld', 20:22:05 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:05 INFO - '_': '/tools/buildbot/bin/python'} 20:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:06 INFO - Downloading/unpacking psutil>=3.1.1 20:22:06 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 20:22:06 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 20:22:06 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 20:22:06 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 20:22:06 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 20:22:06 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 20:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:22:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:08 INFO - Installing collected packages: psutil 20:22:08 INFO - Running setup.py install for psutil 20:22:08 INFO - building 'psutil._psutil_linux' extension 20:22:08 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 20:22:08 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 20:22:08 INFO - building 'psutil._psutil_posix' extension 20:22:08 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 20:22:08 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 20:22:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:08 INFO - Successfully installed psutil 20:22:08 INFO - Cleaning up... 20:22:08 INFO - Return code: 0 20:22:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:22:08 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')]} 20:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:08 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')]} 20:22:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x195e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', '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 20:22:08 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 20:22:08 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 20:22:08 INFO - Using env: {'DISPLAY': ':0', 20:22:08 INFO - 'HOME': '/home/cltbld', 20:22:08 INFO - 'LANG': 'en_US.UTF-8', 20:22:08 INFO - 'LANGUAGE': 'en_US:en', 20:22:08 INFO - 'LOGNAME': 'cltbld', 20:22:08 INFO - 'MAIL': '/var/mail/cltbld', 20:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:08 INFO - 'MOZ_NO_REMOTE': '1', 20:22:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:08 INFO - 'NO_EM_RESTART': '1', 20:22:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:08 INFO - 'PWD': '/builds/slave/test', 20:22:08 INFO - 'SHELL': '/bin/bash', 20:22:08 INFO - 'SHLVL': '1', 20:22:08 INFO - 'TERM': 'linux', 20:22:08 INFO - 'TMOUT': '86400', 20:22:08 INFO - 'USER': 'cltbld', 20:22:08 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:08 INFO - '_': '/tools/buildbot/bin/python'} 20:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:09 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 20:22:09 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 20:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:22:11 INFO - Installing collected packages: mozsystemmonitor 20:22:11 INFO - Running setup.py install for mozsystemmonitor 20:22:11 INFO - Successfully installed mozsystemmonitor 20:22:11 INFO - Cleaning up... 20:22:11 INFO - Return code: 0 20:22:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:22:11 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')]} 20:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:11 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')]} 20:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x195e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', '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 20:22:11 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 20:22:11 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 20:22:11 INFO - Using env: {'DISPLAY': ':0', 20:22:11 INFO - 'HOME': '/home/cltbld', 20:22:11 INFO - 'LANG': 'en_US.UTF-8', 20:22:11 INFO - 'LANGUAGE': 'en_US:en', 20:22:11 INFO - 'LOGNAME': 'cltbld', 20:22:11 INFO - 'MAIL': '/var/mail/cltbld', 20:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:11 INFO - 'MOZ_NO_REMOTE': '1', 20:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:11 INFO - 'NO_EM_RESTART': '1', 20:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:11 INFO - 'PWD': '/builds/slave/test', 20:22:11 INFO - 'SHELL': '/bin/bash', 20:22:11 INFO - 'SHLVL': '1', 20:22:11 INFO - 'TERM': 'linux', 20:22:11 INFO - 'TMOUT': '86400', 20:22:11 INFO - 'USER': 'cltbld', 20:22:11 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:11 INFO - '_': '/tools/buildbot/bin/python'} 20:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:11 INFO - Downloading/unpacking jsonschema==2.5.1 20:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:11 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 20:22:11 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 20:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:22:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:13 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 20:22:13 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 20:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:13 INFO - Downloading functools32-3.2.3-2.tar.gz 20:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:22:13 INFO - warning: no files found matching '*.txt' 20:22:13 INFO - no previously-included directories found matching 'build' 20:22:13 INFO - no previously-included directories found matching 'dist' 20:22:13 INFO - no previously-included directories found matching '.git*' 20:22:13 INFO - Installing collected packages: jsonschema, functools32 20:22:13 INFO - Running setup.py install for functools32 20:22:13 INFO - warning: no files found matching '*.txt' 20:22:13 INFO - no previously-included directories found matching 'build' 20:22:13 INFO - no previously-included directories found matching 'dist' 20:22:13 INFO - no previously-included directories found matching '.git*' 20:22:13 INFO - Successfully installed jsonschema functools32 20:22:13 INFO - Cleaning up... 20:22:14 INFO - Return code: 0 20:22:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:22:14 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')]} 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 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')]} 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 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 0x195e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', '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 20:22:14 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 20:22:14 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 20:22:14 INFO - Using env: {'DISPLAY': ':0', 20:22:14 INFO - 'HOME': '/home/cltbld', 20:22:14 INFO - 'LANG': 'en_US.UTF-8', 20:22:14 INFO - 'LANGUAGE': 'en_US:en', 20:22:14 INFO - 'LOGNAME': 'cltbld', 20:22:14 INFO - 'MAIL': '/var/mail/cltbld', 20:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:14 INFO - 'MOZ_NO_REMOTE': '1', 20:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:14 INFO - 'NO_EM_RESTART': '1', 20:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:14 INFO - 'PWD': '/builds/slave/test', 20:22:14 INFO - 'SHELL': '/bin/bash', 20:22:14 INFO - 'SHLVL': '1', 20:22:14 INFO - 'TERM': 'linux', 20:22:14 INFO - 'TMOUT': '86400', 20:22:14 INFO - 'USER': 'cltbld', 20:22:14 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:14 INFO - '_': '/tools/buildbot/bin/python'} 20:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:22:14 INFO - Cleaning up... 20:22:14 INFO - Return code: 0 20:22:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:22:14 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')]} 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 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')]} 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 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 0x195e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', '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 20:22:14 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 20:22:14 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 20:22:14 INFO - Using env: {'DISPLAY': ':0', 20:22:14 INFO - 'HOME': '/home/cltbld', 20:22:14 INFO - 'LANG': 'en_US.UTF-8', 20:22:14 INFO - 'LANGUAGE': 'en_US:en', 20:22:14 INFO - 'LOGNAME': 'cltbld', 20:22:14 INFO - 'MAIL': '/var/mail/cltbld', 20:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:14 INFO - 'MOZ_NO_REMOTE': '1', 20:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:14 INFO - 'NO_EM_RESTART': '1', 20:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:14 INFO - 'PWD': '/builds/slave/test', 20:22:14 INFO - 'SHELL': '/bin/bash', 20:22:14 INFO - 'SHLVL': '1', 20:22:14 INFO - 'TERM': 'linux', 20:22:14 INFO - 'TMOUT': '86400', 20:22:14 INFO - 'USER': 'cltbld', 20:22:14 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:14 INFO - '_': '/tools/buildbot/bin/python'} 20:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:14 INFO - Downloading/unpacking blobuploader==1.2.4 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:14 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 20:22:14 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 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:16 INFO - Downloading blobuploader-1.2.4.tar.gz 20:22:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:22:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:22:16 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 20:22:16 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 20:22:16 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 20:22:16 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 20:22:16 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 20:22:16 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 20:22:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:22:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:22:16 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 20:22:16 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 20:22:16 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 20:22:16 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 20:22:16 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 20:22:16 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 20:22:17 INFO - Downloading docopt-0.6.1.tar.gz 20:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:22:17 INFO - Installing collected packages: blobuploader, requests, docopt 20:22:17 INFO - Running setup.py install for blobuploader 20:22:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:22:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:22:17 INFO - Running setup.py install for requests 20:22:17 INFO - Running setup.py install for docopt 20:22:17 INFO - Successfully installed blobuploader requests docopt 20:22:17 INFO - Cleaning up... 20:22:17 INFO - Return code: 0 20:22:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:17 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 0x195e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', '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 20:22:17 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 20:22:17 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 20:22:17 INFO - Using env: {'DISPLAY': ':0', 20:22:17 INFO - 'HOME': '/home/cltbld', 20:22:17 INFO - 'LANG': 'en_US.UTF-8', 20:22:17 INFO - 'LANGUAGE': 'en_US:en', 20:22:17 INFO - 'LOGNAME': 'cltbld', 20:22:17 INFO - 'MAIL': '/var/mail/cltbld', 20:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:17 INFO - 'MOZ_NO_REMOTE': '1', 20:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:17 INFO - 'NO_EM_RESTART': '1', 20:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:17 INFO - 'PWD': '/builds/slave/test', 20:22:17 INFO - 'SHELL': '/bin/bash', 20:22:17 INFO - 'SHLVL': '1', 20:22:17 INFO - 'TERM': 'linux', 20:22:17 INFO - 'TMOUT': '86400', 20:22:17 INFO - 'USER': 'cltbld', 20:22:17 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:17 INFO - '_': '/tools/buildbot/bin/python'} 20:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:18 INFO - Running setup.py (path:/tmp/pip-6ZIWcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:18 INFO - Running setup.py (path:/tmp/pip-vfDOFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:18 INFO - Running setup.py (path:/tmp/pip-9wMF1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:18 INFO - Running setup.py (path:/tmp/pip-0he7th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:18 INFO - Running setup.py (path:/tmp/pip-YTT4D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:18 INFO - Running setup.py (path:/tmp/pip-0KZAMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:18 INFO - Running setup.py (path:/tmp/pip-wf20Z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:18 INFO - Running setup.py (path:/tmp/pip-WZqMob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:18 INFO - Running setup.py (path:/tmp/pip-Ia2ZMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:18 INFO - Running setup.py (path:/tmp/pip-lymrxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:18 INFO - Running setup.py (path:/tmp/pip-5ptUAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:18 INFO - Running setup.py (path:/tmp/pip-x6RQoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:19 INFO - Running setup.py (path:/tmp/pip-JYauae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:19 INFO - Running setup.py (path:/tmp/pip-D_9cpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:19 INFO - Running setup.py (path:/tmp/pip-RiYIjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:19 INFO - Running setup.py (path:/tmp/pip-YGu1bN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:19 INFO - Running setup.py (path:/tmp/pip-vSVK2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:22:19 INFO - Running setup.py install for manifestparser 20:22:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:22:19 INFO - Running setup.py install for mozcrash 20:22:19 INFO - Running setup.py install for mozdebug 20:22:19 INFO - Running setup.py install for mozdevice 20:22:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:22:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:22:19 INFO - Running setup.py install for mozfile 20:22:19 INFO - Running setup.py install for mozhttpd 20:22:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:22:19 INFO - Running setup.py install for mozinfo 20:22:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozInstall 20:22:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozleak 20:22:20 INFO - Running setup.py install for mozlog 20:22:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for moznetwork 20:22:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozprocess 20:22:20 INFO - Running setup.py install for mozprofile 20:22:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozrunner 20:22:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozscreenshot 20:22:20 INFO - Running setup.py install for moztest 20:22:21 INFO - Running setup.py install for mozversion 20:22:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:22:21 INFO - Cleaning up... 20:22:21 INFO - Return code: 0 20:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:21 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')]} 20:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:21 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')]} 20:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:21 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 0x195e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', '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 20:22:21 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 20:22:21 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 20:22:21 INFO - Using env: {'DISPLAY': ':0', 20:22:21 INFO - 'HOME': '/home/cltbld', 20:22:21 INFO - 'LANG': 'en_US.UTF-8', 20:22:21 INFO - 'LANGUAGE': 'en_US:en', 20:22:21 INFO - 'LOGNAME': 'cltbld', 20:22:21 INFO - 'MAIL': '/var/mail/cltbld', 20:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:21 INFO - 'MOZ_NO_REMOTE': '1', 20:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:21 INFO - 'NO_EM_RESTART': '1', 20:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:21 INFO - 'PWD': '/builds/slave/test', 20:22:21 INFO - 'SHELL': '/bin/bash', 20:22:21 INFO - 'SHLVL': '1', 20:22:21 INFO - 'TERM': 'linux', 20:22:21 INFO - 'TMOUT': '86400', 20:22:21 INFO - 'USER': 'cltbld', 20:22:21 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:21 INFO - '_': '/tools/buildbot/bin/python'} 20:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:21 INFO - Running setup.py (path:/tmp/pip-j3ng_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:21 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)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:21 INFO - Running setup.py (path:/tmp/pip-08x4Sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:21 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)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:21 INFO - Running setup.py (path:/tmp/pip-Ex_42s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:21 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)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:21 INFO - Running setup.py (path:/tmp/pip-yNpXh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:21 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)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:21 INFO - Running setup.py (path:/tmp/pip-ULwm_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:21 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)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:21 INFO - Running setup.py (path:/tmp/pip-Myzxcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:21 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)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:21 INFO - Running setup.py (path:/tmp/pip-4zmfJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:21 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)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:21 INFO - Running setup.py (path:/tmp/pip-94ZsmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:21 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)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:21 INFO - Running setup.py (path:/tmp/pip-Yd6_VG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:22 INFO - Running setup.py (path:/tmp/pip-JID9jT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:22 INFO - Running setup.py (path:/tmp/pip-khQpNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:22 INFO - Running setup.py (path:/tmp/pip-OMpb0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:22 INFO - Running setup.py (path:/tmp/pip-kH0XvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:22 INFO - Running setup.py (path:/tmp/pip-GERHrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:22 INFO - Running setup.py (path:/tmp/pip-f27Hxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:22 INFO - Running setup.py (path:/tmp/pip-lKZKNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:22 INFO - Running setup.py (path:/tmp/pip-Hgbv4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:22 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 20:22:22 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 20:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:24 INFO - Downloading blessings-1.6.tar.gz 20:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:22:24 INFO - Installing collected packages: blessings 20:22:24 INFO - Running setup.py install for blessings 20:22:24 INFO - Successfully installed blessings 20:22:24 INFO - Cleaning up... 20:22:25 INFO - Return code: 0 20:22:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:25 INFO - Reading from file tmpfile_stdout 20:22:25 INFO - Current package versions: 20:22:25 INFO - argparse == 1.2.1 20:22:25 INFO - blessings == 1.6 20:22:25 INFO - blobuploader == 1.2.4 20:22:25 INFO - docopt == 0.6.1 20:22:25 INFO - functools32 == 3.2.3-2 20:22:25 INFO - jsonschema == 2.5.1 20:22:25 INFO - manifestparser == 1.1 20:22:25 INFO - mozInstall == 1.12 20:22:25 INFO - mozcrash == 1.0 20:22:25 INFO - mozdebug == 0.1 20:22:25 INFO - mozdevice == 0.49 20:22:25 INFO - mozfile == 1.2 20:22:25 INFO - mozhttpd == 0.7 20:22:25 INFO - mozinfo == 0.9 20:22:25 INFO - mozleak == 0.1 20:22:25 INFO - mozlog == 3.4 20:22:25 INFO - moznetwork == 0.27 20:22:25 INFO - mozprocess == 0.25 20:22:25 INFO - mozprofile == 0.28 20:22:25 INFO - mozrunner == 6.13 20:22:25 INFO - mozscreenshot == 0.1 20:22:25 INFO - mozsystemmonitor == 0.3 20:22:25 INFO - moztest == 0.8 20:22:25 INFO - mozversion == 1.4 20:22:25 INFO - psutil == 3.1.1 20:22:25 INFO - requests == 1.2.3 20:22:25 INFO - wsgiref == 0.1.2 20:22:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:25 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')]} 20:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 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')]} 20:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 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 0x195e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', '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 20:22:25 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 20:22:25 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 20:22:25 INFO - Using env: {'DISPLAY': ':0', 20:22:25 INFO - 'HOME': '/home/cltbld', 20:22:25 INFO - 'LANG': 'en_US.UTF-8', 20:22:25 INFO - 'LANGUAGE': 'en_US:en', 20:22:25 INFO - 'LOGNAME': 'cltbld', 20:22:25 INFO - 'MAIL': '/var/mail/cltbld', 20:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:25 INFO - 'MOZ_NO_REMOTE': '1', 20:22:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:25 INFO - 'NO_EM_RESTART': '1', 20:22:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:25 INFO - 'PWD': '/builds/slave/test', 20:22:25 INFO - 'SHELL': '/bin/bash', 20:22:25 INFO - 'SHLVL': '1', 20:22:25 INFO - 'TERM': 'linux', 20:22:25 INFO - 'TMOUT': '86400', 20:22:25 INFO - 'USER': 'cltbld', 20:22:25 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:25 INFO - '_': '/tools/buildbot/bin/python'} 20:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 INFO - Cleaning up... 20:22:25 INFO - Return code: 0 20:22:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:22:25 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')]} 20:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 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')]} 20:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x195e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', '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 20:22:25 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 20:22:25 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 20:22:25 INFO - Using env: {'DISPLAY': ':0', 20:22:25 INFO - 'HOME': '/home/cltbld', 20:22:25 INFO - 'LANG': 'en_US.UTF-8', 20:22:25 INFO - 'LANGUAGE': 'en_US:en', 20:22:25 INFO - 'LOGNAME': 'cltbld', 20:22:25 INFO - 'MAIL': '/var/mail/cltbld', 20:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:25 INFO - 'MOZ_NO_REMOTE': '1', 20:22:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:25 INFO - 'NO_EM_RESTART': '1', 20:22:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:25 INFO - 'PWD': '/builds/slave/test', 20:22:25 INFO - 'SHELL': '/bin/bash', 20:22:25 INFO - 'SHLVL': '1', 20:22:25 INFO - 'TERM': 'linux', 20:22:25 INFO - 'TMOUT': '86400', 20:22:25 INFO - 'USER': 'cltbld', 20:22:25 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:25 INFO - '_': '/tools/buildbot/bin/python'} 20:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:22:25 INFO - Cleaning up... 20:22:25 INFO - Return code: 0 20:22:25 INFO - Running post-action listener: _resource_record_post_action 20:22:25 INFO - Running post-action listener: _start_resource_monitoring 20:22:25 INFO - Starting resource monitoring. 20:22:25 INFO - [mozharness: 2017-02-23 04:22:25.740231Z] Finished create-virtualenv step (success) 20:22:25 INFO - [mozharness: 2017-02-23 04:22:25.740745Z] Running install step. 20:22:25 INFO - Running pre-action listener: _resource_record_pre_action 20:22:25 INFO - Running main action method: install 20:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:25 INFO - Reading from file tmpfile_stdout 20:22:25 INFO - Detecting whether we're running mozinstall >=1.0... 20:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:22:26 INFO - Reading from file tmpfile_stdout 20:22:26 INFO - Output received: 20:22:26 INFO - Usage: mozinstall [options] installer 20:22:26 INFO - Options: 20:22:26 INFO - -h, --help show this help message and exit 20:22:26 INFO - -d DEST, --destination=DEST 20:22:26 INFO - Directory to install application into. [default: 20:22:26 INFO - "/builds/slave/test"] 20:22:26 INFO - --app=APP Application being installed. [default: firefox] 20:22:26 INFO - mkdir: /builds/slave/test/build/application 20:22:26 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'] 20:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:22:41 INFO - Reading from file tmpfile_stdout 20:22:41 INFO - Output received: 20:22:41 INFO - /builds/slave/test/build/application/firefox/firefox 20:22:41 INFO - Running post-action listener: _resource_record_post_action 20:22:41 INFO - [mozharness: 2017-02-23 04:22:41.454265Z] Finished install step (success) 20:22:41 INFO - [mozharness: 2017-02-23 04:22:41.454540Z] Running run-tests step. 20:22:41 INFO - Running pre-action listener: _resource_record_pre_action 20:22:41 INFO - Running main action method: run_tests 20:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:22:41 INFO - Python 2.7.3 20:22:41 INFO - Return code: 0 20:22:41 INFO - grabbing minidump binary from tooltool 20:22:41 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')]} 20:22:41 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 0x1bf9290>, '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 0x1bf9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfa040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:22:41 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 20:22:41 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 20:22:41 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 20:22:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:22:41 INFO - Return code: 0 20:22:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:22:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:22:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:22:41 INFO - ENV: RUST_BACKTRACE is now 1 20:22:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:22:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:22:41 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/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 20:22:41 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/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 20:22:41 INFO - Using env: {'DISPLAY': ':0', 20:22:41 INFO - 'HOME': '/home/cltbld', 20:22:41 INFO - 'LANG': 'en_US.UTF-8', 20:22:41 INFO - 'LANGUAGE': 'en_US:en', 20:22:41 INFO - 'LOGNAME': 'cltbld', 20:22:41 INFO - 'MAIL': '/var/mail/cltbld', 20:22:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:22:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:41 INFO - 'MOZ_NO_REMOTE': '1', 20:22:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:41 INFO - 'NO_EM_RESTART': '1', 20:22:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:41 INFO - 'PWD': '/builds/slave/test', 20:22:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:22:41 INFO - 'RUST_BACKTRACE': '1', 20:22:41 INFO - 'SHELL': '/bin/bash', 20:22:41 INFO - 'SHLVL': '1', 20:22:41 INFO - 'TERM': 'linux', 20:22:41 INFO - 'TMOUT': '86400', 20:22:41 INFO - 'USER': 'cltbld', 20:22:41 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677', 20:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:41 INFO - '_': '/tools/buildbot/bin/python'} 20:22:41 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/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 20:22:41 INFO - mozversion application_buildid: 20170221181700 20:22:41 INFO - mozversion application_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:22:41 INFO - mozversion application_display_name: Nightly 20:22:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:22:41 INFO - mozversion application_name: Firefox 20:22:41 INFO - mozversion application_remotingname: firefox 20:22:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:22:41 INFO - mozversion application_vendor: Mozilla 20:22:41 INFO - mozversion application_version: 54.0a1 20:22:41 INFO - mozversion platform_buildid: 20170221181700 20:22:41 INFO - mozversion platform_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:22:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:22:41 INFO - mozversion platform_version: 54.0a1 20:22:41 INFO - using testdate: 1487823761 20:22:41 INFO - actual date: 1487823761 20:22:41 INFO - starting webserver on 'localhost:41820' 20:22:41 INFO - SUITE-START | Running 2 tests 20:22:41 INFO - TEST-START | dromaeo_css 20:22:41 INFO - operating with platform_type : linux_ 20:22:41 INFO - Initialising browser for dromaeo_css test... 20:22:41 INFO - TEST-INFO | started process 18796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaSCTaR/profile http://localhost:41820/getInfo.html) 20:22:45 INFO - PROCESS | 18796 | Iterating over profile /tmp/tmpaSCTaR/profile 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/blocklist.xml = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/thumbnails = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/places.sqlite-wal = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/places.sqlite = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/gmp = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/safebrowsing = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/extensions.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/extensions.ini = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/cache2 = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/places.sqlite-shm = Wed Feb 22 2017 20:22:43 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/minidumps = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/addons.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/frequencyCap.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/cert8.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/bookmarkbackups = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/pluginreg.dat = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/prefs.js = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/directoryLinks.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/.parentlock = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/crashes = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/secmod.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/compatibility.ini = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | Stat failure 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/content-prefs.sqlite = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/permissions.sqlite = Wed Feb 22 2017 20:22:43 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/localstore.rdf = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/extensions = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:45 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:45 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/key3.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/startupCache = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 18796 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 18796 | Using date: /tmp/tmpaSCTaR/profile/user.js = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 18796 | __metrics Screen width/height:1600/1200 20:22:46 INFO - PROCESS | 18796 | colorDepth:24 20:22:46 INFO - PROCESS | 18796 | Browser inner width/height: 1024/697 20:22:46 INFO - PROCESS | 18796 | __metrics 20:22:47 ERROR - PROCESS | 18796 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:22:48 INFO - TEST-INFO | 18796: exit 0 20:22:48 INFO - Browser initialized. 20:22:48 INFO - Running cycle 1/1 for dromaeo_css test... 20:22:48 INFO - TEST-INFO | started process 18921 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaSCTaR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:23:00 INFO - PROCESS | 18921 | RSS: Main: 153825280 20:23:00 INFO - PROCESS | 18921 | 20:26:04 INFO - PROCESS | 18921 | Cycle 1(1): loaded http://localhost:41820/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41820/tests/dromaeo/cssquery-ext.html) 20:26:05 INFO - PROCESS | 18921 | RSS: Main: 164945920 20:26:05 INFO - PROCESS | 18921 | 20:27:49 INFO - PROCESS | 18921 | ************************* 20:27:49 INFO - PROCESS | 18921 | A coding exception was thrown and uncaught in a Task. 20:27:49 INFO - PROCESS | 18921 | 20:27:49 ERROR - PROCESS | 18921 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:27:49 INFO - PROCESS | 18921 | Full stack: 20:27:49 INFO - PROCESS | 18921 | ************************* 20:29:08 INFO - PROCESS | 18921 | Cycle 1(1): loaded http://localhost:41820/tests/dromaeo/cssquery-ext.html (next: http://localhost:41820/tests/dromaeo/cssquery-jquery.html) 20:29:08 INFO - PROCESS | 18921 | RSS: Main: 158179328 20:29:08 INFO - PROCESS | 18921 | 20:32:11 INFO - PROCESS | 18921 | Cycle 1(1): loaded http://localhost:41820/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41820/tests/dromaeo/cssquery-mootools.html) 20:32:11 INFO - PROCESS | 18921 | RSS: Main: 166223872 20:32:11 INFO - PROCESS | 18921 | 20:35:13 INFO - PROCESS | 18921 | Cycle 1(1): loaded http://localhost:41820/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41820/tests/dromaeo/cssquery-prototype.html) 20:35:14 INFO - PROCESS | 18921 | RSS: Main: 157851648 20:35:14 INFO - PROCESS | 18921 | 20:38:17 INFO - PROCESS | 18921 | Cycle 1(1): loaded http://localhost:41820/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41820/tests/dromaeo/cssquery-yui.html) 20:38:17 INFO - PROCESS | 18921 | RSS: Main: 165482496 20:38:17 INFO - PROCESS | 18921 | 20:41:20 INFO - PROCESS | 18921 | Cycle 1(1): loaded http://localhost:41820/tests/dromaeo/cssquery-yui.html (next: http://localhost:41820/tests/dromaeo/cssquery-dojo.html) 20:41:20 INFO - PROCESS | 18921 | RSS: Main: 159072256 20:41:20 INFO - PROCESS | 18921 | 20:41:20 INFO - PROCESS | 18921 | __start_tp_report 20:41:20 INFO - PROCESS | 18921 | _x_x_mozilla_page_load 20:41:20 INFO - PROCESS | 18921 | _x_x_mozilla_page_load_details 20:41:20 INFO - PROCESS | 18921 | |i|pagename|runs| 20:41:20 INFO - PROCESS | 18921 | |0;dojo.html;2520.9873950630245;2536.5434221840073;2542.1865003198973;2552.8978840846366;2557.6802899637532;4469.4189755331845;4515.683902126852;4572.565606267408;4598.954010459895;4677.181493238687;4503;4525.909481810362;4546.999660074786;4576.5652263035045;4596.793144308505;4140.937885931706;4198.118395137009;4207;4238.046439551092;4276.166147601219;4213.325867861143;4240.491141063061;4244.936325955113;4257.40396344512;4296.226679197744;4081.5102187737366;4105.835766569334;4124.8556300529635;4212.49450065991;4230.957690423087;4154.729942553724;4194.182134483777;4202.180574787918;4235.724677895958;4238.067625122499;4175;4185.707000509966;4186.392973018896;4207.242696314662;4216.367544868263;4347;4364.301711726109;4503.144402563503;4535.523770004135;4556;4822.204336284509;4856.2958371036;4877.853664390074;4905.681130726527;4952.975235123838;4911.066897289504;4915.926261106087;4918.926216106761;4923.433805112386;4951.381077365293;4514.6388288936805;4604.240300350438;4604.332371806069;4606.309053641913;4642.2108241598335;4943.876403089879;4958.206686930074;4983.950160498349;4992.276119962585;5092.439831618519;4664.253719404946;4710.552497512731;4828.637852161102;4840.29815676732;4872.731999740048;4954.380702412199;4959.851204463872;4974.253861920669;5008.248762685554;5031.496850315012;4952.083864485082;4954.232094025359;4959.752012399365;4965.4289756678445;5011.724355160502;4608.078384323148;4704.811807527661;4823.541763532458;4848.757562121879;4859.441164266084;4174.582541745862;4182.330827067654;4183.769892655872;4222.704410691284;4225.260579398593;3643.927121457556;3647.2887786881834;3648.6716195542;3682.3003629310347;3686.9631303687156;4156;4201.642860356904;4205.978970105191;4248.511421186603;4276.2516559601945;4934.753262336869;4951.257311403318;4964.851054468371;4986.626003049786;5012.423571289349;4271.337942618899;4276.700630955866;4288.914221715549;4325.178216138924;4326.524082350938;4275.315949448073;4316.611504964505;4434.711743736647;4438.201123797683;4441.866743997685;4533.455985281853;4542.727436353829;4542.909141817211;4551.271796512542;4554.954450455453;4851.733154676385;4900.754962251945;4939.580135688363;4940.357753492023;4978.576820970258;4651.767411629473;4655.0457156283555;4656.8602941911795;4687.32033855096;4704.1062198182235;4860.781264843161;4865.56209941107;4883.536064074049;4898.779554920108;4914.0908931847725;4771.260454629538;4822.228443449171;4824.372831531879;4989.725565093955;5021.4225364083595;4677.158111539956;4708.340832283415;4711.905761884812;4714.646401519831;4715.693479923932;4718.197906355859;4725.763711814327;4727.290906363935;4765.666403351732;4771.856844294677;4623.976880115578;4625.283081122431;4659.790309436151;4672.042231342499;4713.6936099153445;4660.4174478190225;4704.811807527661;4763.476017638126;4787.0904528139545;4826.8793280168275;4707.364505791811;4720.551547602972;4728.432588089451;4729.69256998294;4785.617150628011 20:41:20 INFO - PROCESS | 18921 | |1;ext.html;538.036913924076;543.8830651409946;552.1359073050676;555.9437069567823;563.9323281206256;36066.81966590167;38069.80965095174;38154.23691526168;41353.58646413535;41380.793096034475;39948.20103597926;40363.990900227436;40380.394294085614;40385.39421908674;40388;7595.126560445564;7746.496477728958;7753.534787912715;7755.185705500898;7763.573003484806;7028.332308430691;7175.9641201794175;9509.429434233933;9772.95113524435;9800.166985806214;9922.50387480623;9968.75078123049;9976.201903847712;9995.95002024989;9997.250206234525;9816.312858099936;9836.704898853046;9895.604175832957;9897.505124743733;9905.2571057171;9535;9636;9639.084286992724;9669.951650241705;9671.90328096717;36350.454743178874;36570.8171459141;36617;36669;36959.815200924095;3411.4882767584813;3486.4421692529327;3490.1972546314237;3533.0460775590445;3553.378158822222;26870.46259074827;27030.864845675842;27187.728122718716;27323.45353092927;27582.862085689645;3299.950500742491;3481.3211423772373;3501.002214368918;3516.7010804081683;3519.9120021999397;229846;231838.8408057966;231887;232079;232133;43431.56568434275;44777.77611111924;53482.73258633682;53501.464985349645;53501.73249133807;51544.74227628838;52936.470635293925;52946;53001.46998530043;53215.733921330146;12166.026717862549;12393.070519711058;12451.564195253122;12495.812562811565;12504.81242781359;9188.540572971324;9264.212541934012;9279.350445468752;9306.395084319498;9315.161635452707;15057.849421505864;15261.465848695243;15308.693826123415;15377;15544.76682849758;6291.937080629135;6297.2128483939505;6365.204349456318;6511.218653761579;6673.699683514254;751.8609057324412;764;776.2703059124404;789.6959670526888;794.9880751788612;25030;25827.74172258291;26015.869920650654;26238;26276.605850912252;901.6168128545341;912.3112050402036;915.0666320353203;919.4575200631525;926.8173451521704;13601;13787.172769633855;14111;14241.287935603386;14252;243370;244974;245606;245763;248146;44576.3313550297;44596.3310550342;44650.10699785986;44759.328610070224;44769.32846007313;1149.2816989381636;1191.606769765975;1195.6174024312136;1201.3872924808236;1210.6005018343926;23626.64560031635;24050.63924041106;24664.50670986534;24829.627555586318;25062.37344066413;36932.261354772214;38516.80741596274;38526.229475410895;38551;38589.42115868208;172029;172559;172626;172889.13555432143;173073;28618.427631446837;29403;32028.19929501781;33632.66367336296;35020;39920.40119398153;40154;40276.3958540628;41070.38394424026;41146.38280425856;34214;34234.48648270228;34400.82799585986;34622;35005.64994350024;32422.513662295558;32733.672663274014;32753.34493310073;33203.50194747032;33584.42035642152;33232.1691957703;33712.49431258582;33965;33978.660213398536;34066.65933340635;24592.877035614707;26522.60216096799;28056.859715701292;28381.148565543066;29761.702382975895;22312.553748924605;22702.772972270726;22820.20129295533;23062.769372306062;23068 20:41:20 INFO - PROCESS | 18921 | |2;jquery.html;392.1353415718338;399.4527497328659;399.9760014399136;406.6726285340301;425.09666957714853;12526.686832829184;16094.83905160948;16337.101459419728;17819;17871.73192402115;16846.157692115376;17049.57376065599;17406.216720247587;17553.034583097924;17568.385106521273;5118.564921981636;5349.491798279158;5357.303550538424;5366.221897824813;5611.579131565129;5724.370319264877;5754.482096611304;5756;5759.625624334426;5776;5361.571074314065;5614.831555053355;5620.269364982567;5733.455321744427;5744.569157313218;5672.9432705672825;5705.400932902048;5719.056355701305;5752.165935939286;6178.165947597106;5585.720713964285;5616.129499927518;5616.747246373923;5616.887662246732;5631.746571404296;12902.677433064155;13117.344132793321;13186.934065329708;13568.59294221175;13611.319434028357;2238.552289542098;2430.343807172054;2488.365466805971;2537.112010796225;2557.475717477931;9124.58939347731;9242.075792420772;9612.66355677555;9905.059019393146;9974.152197063258;1660.867130629559;1674.137819023204;1680.8655307575486;1685.8988460692399;1710.683523548135;75541;78721.60639196825;79289.60355198245;80840;81042;10633.574657013633;10680.78638427227;10765.83851242232;10882.23824332305;11089.611863584898;13601.523946661819;15468.922655386876;15498.612534686723;15538.844611553739;15575.299111540007;6115.419035191739;6254.966341324454;6605.042268870987;6636.13730215069;6760.222574403908;5710.7430165642645;5737.77048918047;5915.674637894957;5928.822135335947;5934.317553481319;4244.108737165168;4389.956100439019;4457.2199865023495;4461.107778444324;4834.685745426536;1869.906104928632;1926.6050459656017;1947.7857435682063;2000.7699114602008;2001.4395969128668;776.1539921485495;803.586153131138;807.7617102954642;810.2100452059312;837.1461109668226;17326.74009889853;17375.913120434318;17922.91038544825;17970.640587188183;18381.81618183828;777.5801067423641;789.5933594198992;790.9683612655544;792.9524228546305;819.1685439279137;9639.951800240953;9776.51117444125;9957.452340121363;9991.600335986479;10172.745681358025;457261;461271;462726;462821;464079;84434.5778271117;86630.56684716536;88750.279343481;88838.55580722184;89214;891.5230351761776;904.1500989070253;908.6002159049997;909.8043920557112;913.6071489259549;14889;15262.08427494354;15288.847111528743;15772.369105236121;15991.360345586047;37325.44011839879;37727.434088489244;38355.42466863055;38634;38845.41731874081;46176.7691161542;48423.5157648419;48974;54645.45354546563;55198;7874.330681892104;8135.186481351818;8140.674373025013;8468.66125354979;8518;11276.267042642203;12271.509139634672;12633.2420054797;12785.488580456678;13170.868291316965;10421.374717516974;10587.576496940035;10603.681889543326;11119.888801111885;11206.775864482828;10657.520411581343;10782.838257425989;10920.344779313267;10930.070943969851;11029.283096598696;10226.6420675276;10344.534495947852;10692.358458492516;11163;11328.490217940378;8085.5957202140835;8166.714165004197;8320.417570769989;8356.080831108695;8729.432586881832;7706.229377062249;9781.804363912825;10516.369017858953;10831.24181307301;10949.616763413242 20:41:20 INFO - PROCESS | 18921 | |3;mootools.html;1346.4816045822358;1359.1776974930167;1363.884069854063;1373.1349249972516;1379.096691666958;7460.403167746581;7580.317771400573;7761.301482866541;7879.212078792118;7896.8420631587405;7894.6052697365185;7980.481268717529;8013.799655008627;8035.075966263881;8043.276105150535;2068.9999999999964;2097.622427962966;2103.5161912760072;2104.337133802855;2106.536561956367;2071.388213329397;2094.413564202026;2099.128861522466;2101.53766171442;2107.072887929306;2020.4948762809297;2048.6107639548436;2053.4147767886157;2054.311805545137;2067.266120527214;2036.5519585691197;2049.1700861151257;2054.2230970210735;2054.753429588444;2055.4553043443498;1692.1200975492798;1739.3564381179044;1956.872803267783;2000.3098930868828;2052.445839623308;7863.56750378729;7896.012998375203;7898.842023159562;7906.248906353887;7907.209279072104;4314.935275970863;4450.221211288012;4461.442319710036;4462.152191083716;4475.977620111911;2763.9447211055867;2810.775137988956;2826.434713057397;2884.769218462518;2899.5795609636693;1030.062642994871;1039.9220058495644;1053.4732633683159;1067.9733006674817;1087.8205096159124;10029.799404011952;10196.643117490927;10234.48827558619;10269.178465722724;10282.07461328484;3803.82882770276;3848.826802793881;3964.32606456903;4010.799460026987;4021;8142.592870356459;8153.510789352657;8162.877556836652;8168.591570421455;8178.959105204436;4428.202923473806;4453.0648563801315;4456.532064133284;4460.687751857404;4461.866144015685;4037.2127435149864;4066.1461093170174;4105.876823695234;4121.093359460914;4134.875953721393;3278.9836050819595;3344.6153692325693;3371.6628337166576;3379.2903490267317;3384.1539615096226;997.9301448898652;997.9600815967425;1006.5520843224841;1013.8631284776608;1013.9543720532594;647.5240698086902;668.4017804065335;671.9798406047827;672.7342699633617;680.2415306932834;4772.618190544748;4822.397200349957;4844.75776211188;4905.632077594195;4998.225275082368;489.08052860622513;504.684572142411;505.54753495621213;522.3288074823834;547.5428017605344;3370.191154122993;3400.846961886721;3441.4493681011418;3447.7586568939932;3601.3157500074904;8147.470414423044;8558.272546833465;8659.13408659129;9036.186743193013;9176.082391760903;9325.533723313807;9372.625094996123;9372.67195648149;9375.437473751597;9420.858687119699;6567.080608714784;6813.182418109858;6908.447324214051;6975.965120174366;6988.19635741886;5195.792168313225;5340.118880384848;5427.077396842626;5546.75039623226;5632.042552765957;8233.83532329338;8397.076321604742;8471.745847624581;8501.234888860032;8777.868331974898;8503.617337219963;8525.616347264264;8588.625819869041;8588.699395521127;8643.17889800468;6563.442107420923;6759.898601521079;6848.178218613798;6930.549514281555;6970.407515361251;7498.437617178624;7515.173330933483;7580.166181720117;7610.695572177051;7637.236276372319;6718.798436046926;6757.493188010821;6885.862282754417;6909.343612356818;6943.305669433016;6641.966790166019;6710.698018589078;6746.545983771458;6899.931000689929;6912.135983002125;5830.183774271521;5875.41245875409;5929.6145750526075;5952.0476723724;5966.642001479925;4546.590806827401;4575.542445755398;4590.196715574694;4608.193566125981;4725.787339569659;4429.579189976947;4514.571115743999;4526.841560545365;4587.197240482836;4639.141758774637 20:41:20 INFO - PROCESS | 18921 | |4;prototype.html;365.51021631014436;366.6388607221888;369.1620022548813;373.46594370050815;377.6506731273573;4911.7052976821415;4976.4774698656665;4979.427365852924;4989.156180976845;4998.650094493382;4758.595519380848;4881.755912204393;4897.412310522734;4906.1168989581865;4929.86613078992;3960.9801950990204;3991.10866904767;4000.7799571023656;4004.579519150491;4033.5361433435237;3949.486566746333;3991.846441311375;4006.519217693881;4010.598940105995;4020.1758639478985;3984.6413822755944;3984.8605298814546;3988.1425493518886;3996.5403978542404;4010.779407132621;3917.5690674025836;3919.3337132687225;3924.7841368724708;3976.582458841809;4023.396490526415;3573.5175751273505;3573.94639080414;3583.8566457341676;3594.8382322795537;3604.837782299777;4862.197737373364;4864.927026094611;4870.537298956593;4879.219085033973;4881.4630390657;4457.77711114443;4561.771911404449;4579.610733087691;4594.95405045952;4606.792694328729;5180.922286165711;5200.843974680765;5214.8696282592855;5248.370195576517;5285.7621407036395;3934.370500719871;3960.702947278937;3976.304146774332;3985.262726395597;3995.5405128410316;4889.657723959324;5258.211268309746;5442.401335853053;5455.863603409907;5459.781608735646;3937.1928754605237;4019.457373254632;4036.5761595032686;4042.757434553936;4050.736702114353;4067.9389809152885;4071.185762847442;4095.7747323896874;4115.341545352789;4120.505539335299;4561.589456948891;4562.84030058953;4571.817127314936;4599.310103484504;4611.123886461562;5150.768215430315;5245.7114858683135;5282.577393808563;5286.286351342519;5316.9202461963105;1384.5777038003505;1401.3833913078213;1401.3903951781008;1406.810080639121;1410.0975375759522;954.6324665004023;987.5506644476745;992.6376872441507;993.6820217530466;1002.1631937332263;4453.131639330364;4462.174497717932;4467;4468.754218517947;4497.257952437876;5271.367435907716;5297.060131626984;5306.230596563477;5310.150375939831;5328.706921119298;4296.742195468282;4450.5549445055885;4486.59620634138;4497.740632622871;4514.774261286922;2756.476269508827;2786.4427114577165;2792.5252707039854;2792.5392310268985;2806.368567072433;4897.632677549198;4906.411230652273;4913.1893237616105;4913.287573301851;4932.827351042768;5120.820771272987;5315.973420132875;5335.893282134336;5360.195970604441;5374.462553744673;4841.782119804618;4866.829660961849;4870.95129048705;4883.316335713074;4887.535684110004;5216.113260745607;5224.869378265574;5238.97380513095;5257.211418287287;5276.419593844751;4944.629152813625;5126.871828204325;5156.226566015128;5196.376434827845;5206.635535512478;4883.8290659826735;4893.916832395568;4919.508049195052;4920.753962301942;4933.827316043921;4000.239954408653;4002.2795896737703;4039.8384064637085;4043.1913617276073;4043.2317859606583;5078.451072422947;5121.436641969455;5237.921431178612;5274.813167037386;5284.603654725834;5100.38795344561;5112.488751124858;5119.590432765444;5130.589552835839;5166.2508936203285;5025.522575355335;5118.797082685492;5125.43620201785;5168.896622067613;5178.32681751385;4867.902641947212;5078.238264260391;5186.559142472781;5220.451852555579;5222.399424066281;5296.179092240709;5308.336457942757;5321.3880403754065;5332.520073193431;5338.893222135458;4997.8027246213705;5186.351706036746;5190.247414125006;5231.293775340432;5232.764525596282 20:41:20 INFO - PROCESS | 18921 | |5;yui.html;628.8868003759321;645.9192600924881;701.7894631610519;725.5030304241595;758.1054355860086;11860.110491713123;12183.939080304586;12256.203346782475;12315.568955086574;12340.938295308533;12192.329421881816;12860.871391286084;12872.678183045406;12877.806832897491;13011.544595939144;782.706485068098;803.758872338299;809.1382677448498;812.0539571399313;812.4881324765419;786.9645865936018;799.6921185343656;814.6008455856647;823.9917600824004;825.6573521988367;856.4775486952954;858.459170722444;858.4806192253691;858.527809704663;859.0121360435518;841.8021764885247;845.5687599324327;846.8899043124385;848.8981322241309;850.8468475674376;620.7206756959387;626.4330780643525;626.9090981807658;626.937306269374;627.1783963008473;13132.0807543472;13234.404451799595;13240.73518529624;13252.138610990254;13510.324483775772;11283.097352211804;11469.713257168554;12292.323922184276;12731.363431828371;13221.801672974912;11569.479373428216;11605.709857253552;11734.589289374917;11845.111616628701;12155.878441215564;3951.7826519541413;4000.819963101638;4049.3521036633997;4069.918601627951;4211.241976444238;54925;56559;57210.14184787231;58867;59709;9416.529173541296;9476.763080923032;9593.184579310697;9680.515974201262;9682.322237443312;17776;17892.46322610324;18007.72988405175;18156.63686726258;18271.1777969989;6095.725692343855;6102.816915492543;6116.082587611894;6145.84635384119;6192.040233763774;3550.6626870447267;3640.8543658253902;3647.544056992876;3662.047867554455;3712.053426376257;2968.1095671298526;3016.909492715222;3034.9544756828664;3044.1476386611785;3059.3116548776793;508.76342500737337;514.5703337712977;522.908491014071;528.6616565398148;530.1888111189886;536.5600207829542;549.85978575463;568.1165787200943;569.7891780041411;574.1789241384777;7365.674178647895;7747.147813740484;7850.960745196352;7950.284474397216;8054.23484768946;534.1453674121452;542.4114835403552;544.7004147718739;554.2406902543541;566.8356176708791;3683.263347330491;4269.807858646306;4357.716748411343;4537.115262523776;4564.543545645409;58144.709276453345;60247.39752602505;60318.69840650768;60725;63818;51624.48375516197;52240.21639675408;54235.45764542383;54672.72663636656;54679.99999999921;38645.03387415281;46675.29987050197;48016.27975580369;48816;49221;8242.381821363302;8762.167594078552;8780;8790.516521591373;8999.865002024844;32560.674393255766;32944;33848.1537961553;34181.145471363416;34285.828570857986;40073.398899015956;42838.78580607202;43472;43520.34719479147;43865.12269754651;10218.591256349662;10316.535755890853;10319;10407.571683023098;10491.108255798037;11429.449038132672;11547.162681543243;11582.88417115818;11858.288502689866;11973.521059157536;10599.682009539727;10820.350778953289;10972.506237219184;11190.776184476428;11446.515296580545;11112.927920562472;11189.510638403186;11517.654470365902;11537.866886112633;11589.24669896454;11164.944175279397;11275.687017397418;11408.926525084576;11590.362530060927;11769.764604708029;9184.908150918673;9492.95253523728;9511.952440237754;9580.377275477073;9709.6601618943;8634.78413039679;8656.371154695646;9167.77080572991;9281.814363712552;9351.5791789371 20:41:20 INFO - PROCESS | 18921 | __end_tp_report 20:41:20 INFO - PROCESS | 18921 | __start_cc_report 20:41:20 INFO - PROCESS | 18921 | _x_x_mozilla_cycle_collect,384 20:41:20 INFO - PROCESS | 18921 | __end_cc_report 20:41:20 INFO - PROCESS | 18921 | __startTimestamp1487824880696__endTimestamp 20:41:20 INFO - PROCESS | 18921 | 20:41:20 INFO - PROCESS | 18921 | ------- Summary: start ------- 20:41:20 INFO - PROCESS | 18921 | Number of tests: 6 20:41:20 INFO - PROCESS | 18921 | 20:41:20 INFO - PROCESS | 18921 | [#0] dojo.html Cycles:165 Average:4523.00 Median:4651.77 stddev:475.99 (10.2%) stddev-sans-first:450.80 20:41:20 INFO - PROCESS | 18921 | Values: 2521.0 2536.5 2542.2 2552.9 2557.7 4469.4 4515.7 4572.6 4599.0 4677.2 4503.0 4525.9 4547.0 4576.6 4596.8 4140.9 4198.1 4207.0 4238.0 4276.2 4213.3 4240.5 4244.9 4257.4 4296.2 4081.5 4105.8 4124.9 4212.5 4231.0 4154.7 4194.2 4202.2 4235.7 4238.1 4175.0 4185.7 4186.4 4207.2 4216.4 4347.0 4364.3 4503.1 4535.5 4556.0 4822.2 4856.3 4877.9 4905.7 4953.0 4911.1 4915.9 4918.9 4923.4 4951.4 4514.6 4604.2 4604.3 4606.3 4642.2 4943.9 4958.2 4984.0 4992.3 5092.4 4664.3 4710.6 4828.6 4840.3 4872.7 4954.4 4959.9 4974.3 5008.2 5031.5 4952.1 4954.2 4959.8 4965.4 5011.7 4608.1 4704.8 4823.5 4848.8 4859.4 4174.6 4182.3 4183.8 4222.7 4225.3 3643.9 3647.3 3648.7 3682.3 3687.0 4156.0 4201.6 4206.0 4248.5 4276.3 4934.8 4951.3 4964.9 4986.6 5012.4 4271.3 4276.7 4288.9 4325.2 4326.5 4275.3 4316.6 4434.7 4438.2 4441.9 4533.5 4542.7 4542.9 4551.3 4555.0 4851.7 4900.8 4939.6 4940.4 4978.6 4651.8 4655.0 4656.9 4687.3 4704.1 4860.8 4865.6 4883.5 4898.8 4914.1 4771.3 4822.2 4824.4 4989.7 5021.4 4677.2 4708.3 4711.9 4714.6 4715.7 4718.2 4725.8 4727.3 4765.7 4771.9 4624.0 4625.3 4659.8 4672.0 4713.7 4660.4 4704.8 4763.5 4787.1 4826.9 4707.4 4720.6 4728.4 4729.7 4785.6 20:41:20 INFO - PROCESS | 18921 | 20:41:20 INFO - PROCESS | 18921 | [#1] ext.html Cycles:180 Average:37943.56 Median:36208.64 stddev:56836.07 (157.0%) stddev-sans-first:56926.11 20:41:20 INFO - PROCESS | 18921 | Values: 538.0 543.9 552.1 555.9 563.9 36066.8 38069.8 38154.2 41353.6 41380.8 39948.2 40364.0 40380.4 40385.4 40388.0 7595.1 7746.5 7753.5 7755.2 7763.6 7028.3 7176.0 9509.4 9773.0 9800.2 9922.5 9968.8 9976.2 9996.0 9997.3 9816.3 9836.7 9895.6 9897.5 9905.3 9535.0 9636.0 9639.1 9670.0 9671.9 36350.5 36570.8 36617.0 36669.0 36959.8 3411.5 3486.4 3490.2 3533.0 3553.4 26870.5 27030.9 27187.7 27323.5 27582.9 3300.0 3481.3 3501.0 3516.7 3519.9 229846.0 231838.8 231887.0 232079.0 232133.0 43431.6 44777.8 53482.7 53501.5 53501.7 51544.7 52936.5 52946.0 53001.5 53215.7 12166.0 12393.1 12451.6 12495.8 12504.8 9188.5 9264.2 9279.4 9306.4 9315.2 15057.8 15261.5 15308.7 15377.0 15544.8 6291.9 6297.2 6365.2 6511.2 6673.7 751.9 764.0 776.3 789.7 795.0 25030.0 25827.7 26015.9 26238.0 26276.6 901.6 912.3 915.1 919.5 926.8 13601.0 13787.2 14111.0 14241.3 14252.0 243370.0 244974.0 245606.0 245763.0 248146.0 44576.3 44596.3 44650.1 44759.3 44769.3 1149.3 1191.6 1195.6 1201.4 1210.6 23626.6 24050.6 24664.5 24829.6 25062.4 36932.3 38516.8 38526.2 38551.0 38589.4 172029.0 172559.0 172626.0 172889.1 173073.0 28618.4 29403.0 32028.2 33632.7 35020.0 39920.4 40154.0 40276.4 41070.4 41146.4 34214.0 34234.5 34400.8 34622.0 35005.6 32422.5 32733.7 32753.3 33203.5 33584.4 33232.2 33712.5 33965.0 33978.7 34066.7 24592.9 26522.6 28056.9 28381.1 29761.7 22312.6 22702.8 22820.2 23062.8 23068.0 20:41:20 INFO - PROCESS | 18921 | 20:41:20 INFO - PROCESS | 18921 | [#2] jquery.html Cycles:180 Average:26883.15 Median:254451.38 stddev:76206.58 (29.9%) stddev-sans-first:76394.40 20:41:20 INFO - PROCESS | 18921 | Values: 392.1 399.5 400.0 406.7 425.1 12526.7 16094.8 16337.1 17819.0 17871.7 16846.2 17049.6 17406.2 17553.0 17568.4 5118.6 5349.5 5357.3 5366.2 5611.6 5724.4 5754.5 5756.0 5759.6 5776.0 5361.6 5614.8 5620.3 5733.5 5744.6 5672.9 5705.4 5719.1 5752.2 6178.2 5585.7 5616.1 5616.7 5616.9 5631.7 12902.7 13117.3 13186.9 13568.6 13611.3 2238.6 2430.3 2488.4 2537.1 2557.5 9124.6 9242.1 9612.7 9905.1 9974.2 1660.9 1674.1 1680.9 1685.9 1710.7 75541.0 78721.6 79289.6 80840.0 81042.0 10633.6 10680.8 10765.8 10882.2 11089.6 13601.5 15468.9 15498.6 15538.8 15575.3 6115.4 6255.0 6605.0 6636.1 6760.2 5710.7 5737.8 5915.7 5928.8 5934.3 4244.1 4390.0 4457.2 4461.1 4834.7 1869.9 1926.6 1947.8 2000.8 2001.4 776.2 803.6 807.8 810.2 837.1 17326.7 17375.9 17922.9 17970.6 18381.8 777.6 789.6 791.0 793.0 819.2 9640.0 9776.5 9957.5 9991.6 10172.7 457261.0 461271.0 462726.0 462821.0 464079.0 84434.6 86630.6 88750.3 88838.6 89214.0 891.5 904.2 908.6 909.8 913.6 14889.0 15262.1 15288.8 15772.4 15991.4 37325.4 37727.4 38355.4 38634.0 38845.4 46176.8 48423.5 48974.0 54645.5 55198.0 7874.3 8135.2 8140.7 8468.7 8518.0 11276.3 12271.5 12633.2 12785.5 13170.9 10421.4 10587.6 10603.7 11119.9 11206.8 10657.5 10782.8 10920.3 10930.1 11029.3 10226.6 10344.5 10692.4 11163.0 11328.5 8085.6 8166.7 8320.4 8356.1 8729.4 7706.2 9781.8 10516.4 10831.2 10949.6 20:41:20 INFO - PROCESS | 18921 | 20:41:20 INFO - PROCESS | 18921 | [#3] mootools.html Cycles:180 Average:4962.58 Median:2666.92 stddev:2802.08 (105.1%) stddev-sans-first:2796.77 20:41:20 INFO - PROCESS | 18921 | Values: 1346.5 1359.2 1363.9 1373.1 1379.1 7460.4 7580.3 7761.3 7879.2 7896.8 7894.6 7980.5 8013.8 8035.1 8043.3 2069.0 2097.6 2103.5 2104.3 2106.5 2071.4 2094.4 2099.1 2101.5 2107.1 2020.5 2048.6 2053.4 2054.3 2067.3 2036.6 2049.2 2054.2 2054.8 2055.5 1692.1 1739.4 1956.9 2000.3 2052.4 7863.6 7896.0 7898.8 7906.2 7907.2 4314.9 4450.2 4461.4 4462.2 4476.0 2763.9 2810.8 2826.4 2884.8 2899.6 1030.1 1039.9 1053.5 1068.0 1087.8 10029.8 10196.6 10234.5 10269.2 10282.1 3803.8 3848.8 3964.3 4010.8 4021.0 8142.6 8153.5 8162.9 8168.6 8179.0 4428.2 4453.1 4456.5 4460.7 4461.9 4037.2 4066.1 4105.9 4121.1 4134.9 3279.0 3344.6 3371.7 3379.3 3384.2 997.9 998.0 1006.6 1013.9 1014.0 647.5 668.4 672.0 672.7 680.2 4772.6 4822.4 4844.8 4905.6 4998.2 489.1 504.7 505.5 522.3 547.5 3370.2 3400.8 3441.4 3447.8 3601.3 8147.5 8558.3 8659.1 9036.2 9176.1 9325.5 9372.6 9372.7 9375.4 9420.9 6567.1 6813.2 6908.4 6976.0 6988.2 5195.8 5340.1 5427.1 5546.8 5632.0 8233.8 8397.1 8471.7 8501.2 8777.9 8503.6 8525.6 8588.6 8588.7 8643.2 6563.4 6759.9 6848.2 6930.5 6970.4 7498.4 7515.2 7580.2 7610.7 7637.2 6718.8 6757.5 6885.9 6909.3 6943.3 6642.0 6710.7 6746.5 6899.9 6912.1 5830.2 5875.4 5929.6 5952.0 5966.6 4546.6 4575.5 4590.2 4608.2 4725.8 4429.6 4514.6 4526.8 4587.2 4639.1 20:41:20 INFO - PROCESS | 18921 | 20:41:20 INFO - PROCESS | 18921 | [#4] prototype.html Cycles:180 Average:4346.01 Median:4881.61 stddev:1202.23 (24.6%) stddev-sans-first:1167.89 20:41:20 INFO - PROCESS | 18921 | Values: 365.5 366.6 369.2 373.5 377.7 4911.7 4976.5 4979.4 4989.2 4998.7 4758.6 4881.8 4897.4 4906.1 4929.9 3961.0 3991.1 4000.8 4004.6 4033.5 3949.5 3991.8 4006.5 4010.6 4020.2 3984.6 3984.9 3988.1 3996.5 4010.8 3917.6 3919.3 3924.8 3976.6 4023.4 3573.5 3573.9 3583.9 3594.8 3604.8 4862.2 4864.9 4870.5 4879.2 4881.5 4457.8 4561.8 4579.6 4595.0 4606.8 5180.9 5200.8 5214.9 5248.4 5285.8 3934.4 3960.7 3976.3 3985.3 3995.5 4889.7 5258.2 5442.4 5455.9 5459.8 3937.2 4019.5 4036.6 4042.8 4050.7 4067.9 4071.2 4095.8 4115.3 4120.5 4561.6 4562.8 4571.8 4599.3 4611.1 5150.8 5245.7 5282.6 5286.3 5316.9 1384.6 1401.4 1401.4 1406.8 1410.1 954.6 987.6 992.6 993.7 1002.2 4453.1 4462.2 4467.0 4468.8 4497.3 5271.4 5297.1 5306.2 5310.2 5328.7 4296.7 4450.6 4486.6 4497.7 4514.8 2756.5 2786.4 2792.5 2792.5 2806.4 4897.6 4906.4 4913.2 4913.3 4932.8 5120.8 5316.0 5335.9 5360.2 5374.5 4841.8 4866.8 4871.0 4883.3 4887.5 5216.1 5224.9 5239.0 5257.2 5276.4 4944.6 5126.9 5156.2 5196.4 5206.6 4883.8 4893.9 4919.5 4920.8 4933.8 4000.2 4002.3 4039.8 4043.2 4043.2 5078.5 5121.4 5237.9 5274.8 5284.6 5100.4 5112.5 5119.6 5130.6 5166.3 5025.5 5118.8 5125.4 5168.9 5178.3 4867.9 5078.2 5186.6 5220.5 5222.4 5296.2 5308.3 5321.4 5332.5 5338.9 4997.8 5186.4 5190.2 5231.3 5232.8 20:41:20 INFO - PROCESS | 18921 | 20:41:20 INFO - PROCESS | 18921 | [#5] yui.html Cycles:180 Average:13952.25 Median:511.67 stddev:16862.87 (3295.7%) stddev-sans-first:16880.49 20:41:20 INFO - PROCESS | 18921 | Values: 628.9 645.9 701.8 725.5 758.1 11860.1 12183.9 12256.2 12315.6 12340.9 12192.3 12860.9 12872.7 12877.8 13011.5 782.7 803.8 809.1 812.1 812.5 787.0 799.7 814.6 824.0 825.7 856.5 858.5 858.5 858.5 859.0 841.8 845.6 846.9 848.9 850.8 620.7 626.4 626.9 626.9 627.2 13132.1 13234.4 13240.7 13252.1 13510.3 11283.1 11469.7 12292.3 12731.4 13221.8 11569.5 11605.7 11734.6 11845.1 12155.9 3951.8 4000.8 4049.4 4069.9 4211.2 54925.0 56559.0 57210.1 58867.0 59709.0 9416.5 9476.8 9593.2 9680.5 9682.3 17776.0 17892.5 18007.7 18156.6 18271.2 6095.7 6102.8 6116.1 6145.8 6192.0 3550.7 3640.9 3647.5 3662.0 3712.1 2968.1 3016.9 3035.0 3044.1 3059.3 508.8 514.6 522.9 528.7 530.2 536.6 549.9 568.1 569.8 574.2 7365.7 7747.1 7851.0 7950.3 8054.2 534.1 542.4 544.7 554.2 566.8 3683.3 4269.8 4357.7 4537.1 4564.5 58144.7 60247.4 60318.7 60725.0 63818.0 51624.5 52240.2 54235.5 54672.7 54680.0 38645.0 46675.3 48016.3 48816.0 49221.0 8242.4 8762.2 8780.0 8790.5 8999.9 32560.7 32944.0 33848.2 34181.1 34285.8 40073.4 42838.8 43472.0 43520.3 43865.1 10218.6 10316.5 10319.0 10407.6 10491.1 11429.4 11547.2 11582.9 11858.3 11973.5 10599.7 10820.4 10972.5 11190.8 11446.5 11112.9 11189.5 11517.7 11537.9 11589.2 11164.9 11275.7 11408.9 11590.4 11769.8 9184.9 9493.0 9512.0 9580.4 9709.7 8634.8 8656.4 9167.8 9281.8 9351.6 20:41:20 INFO - PROCESS | 18921 | -------- Summary: end -------- 20:41:20 INFO - PROCESS | 18921 | 20:41:21 INFO - TEST-INFO | 18921: exit 0 20:41:21 INFO - TEST-OK | dromaeo_css | took 1119965ms 20:41:21 INFO - TEST-START | kraken 20:41:21 INFO - operating with platform_type : linux_ 20:41:21 INFO - Initialising browser for kraken test... 20:41:21 INFO - TEST-INFO | started process 19293 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1c04tw/profile http://localhost:41820/getInfo.html) 20:41:24 INFO - PROCESS | 19293 | Iterating over profile /tmp/tmp1c04tw/profile 20:41:25 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/blocklist.xml = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/thumbnails = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 19293 | Stat failure 20:41:25 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/places.sqlite-wal = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/places.sqlite = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/webappsstore.sqlite = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/gmp = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/safebrowsing = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/extensions.json = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/extensions.ini = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | __metrics Screen width/height:1600/1200 20:41:26 INFO - PROCESS | 19293 | colorDepth:24 20:41:26 INFO - PROCESS | 19293 | Browser inner width/height: 1024/697 20:41:26 INFO - PROCESS | 19293 | __metrics 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/cache2 = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/places.sqlite-shm = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/minidumps = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/addons.json = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/frequencyCap.json = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/cert8.db = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/bookmarkbackups = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/pluginreg.dat = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/prefs.js = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/directoryLinks.json = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/mimeTypes.rdf = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/.parentlock = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/crashes = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/secmod.db = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/compatibility.ini = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | Stat failure 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/content-prefs.sqlite = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/permissions.sqlite = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/localstore.rdf = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/extensions = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/key3.db = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/startupCache = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 19293 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 19293 | Using date: /tmp/tmp1c04tw/profile/user.js = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:27 ERROR - PROCESS | 19293 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:41:28 INFO - TEST-INFO | 19293: exit 0 20:41:28 INFO - Browser initialized. 20:41:28 INFO - Running cycle 1/1 for kraken test... 20:41:28 INFO - TEST-INFO | started process 19419 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1c04tw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:41:40 INFO - PROCESS | 19419 | RSS: Main: 154050560 20:41:40 INFO - PROCESS | 19419 | 20:41:43 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41820/tests/kraken/driver.html?testName=audio-beat-detection) 20:41:44 INFO - PROCESS | 19419 | RSS: Main: 160481280 20:41:44 INFO - PROCESS | 19419 | 20:41:46 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41820/tests/kraken/driver.html?testName=audio-dft) 20:41:47 INFO - PROCESS | 19419 | RSS: Main: 159186944 20:41:47 INFO - PROCESS | 19419 | 20:41:50 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41820/tests/kraken/driver.html?testName=audio-fft) 20:41:51 INFO - PROCESS | 19419 | RSS: Main: 155742208 20:41:51 INFO - PROCESS | 19419 | 20:41:53 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41820/tests/kraken/driver.html?testName=audio-oscillator) 20:41:54 INFO - PROCESS | 19419 | RSS: Main: 156237824 20:41:54 INFO - PROCESS | 19419 | 20:41:56 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41820/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:41:56 INFO - PROCESS | 19419 | RSS: Main: 156655616 20:41:56 INFO - PROCESS | 19419 | 20:41:59 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41820/tests/kraken/driver.html?testName=imaging-darkroom) 20:42:00 INFO - PROCESS | 19419 | RSS: Main: 161488896 20:42:00 INFO - PROCESS | 19419 | 20:42:04 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41820/tests/kraken/driver.html?testName=imaging-desaturate) 20:42:04 INFO - PROCESS | 19419 | RSS: Main: 158105600 20:42:04 INFO - PROCESS | 19419 | 20:42:07 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41820/tests/kraken/driver.html?testName=json-parse-financial) 20:42:08 INFO - PROCESS | 19419 | RSS: Main: 158597120 20:42:08 INFO - PROCESS | 19419 | 20:42:10 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41820/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:42:10 INFO - PROCESS | 19419 | RSS: Main: 159207424 20:42:10 INFO - PROCESS | 19419 | 20:42:12 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41820/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:42:13 INFO - PROCESS | 19419 | RSS: Main: 159363072 20:42:13 INFO - PROCESS | 19419 | 20:42:15 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41820/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:42:16 INFO - PROCESS | 19419 | RSS: Main: 160256000 20:42:16 INFO - PROCESS | 19419 | 20:42:19 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41820/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:42:19 INFO - PROCESS | 19419 | RSS: Main: 160526336 20:42:19 INFO - PROCESS | 19419 | 20:42:23 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41820/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:42:23 INFO - PROCESS | 19419 | RSS: Main: 160006144 20:42:23 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | Cycle 1(1): loaded http://localhost:41820/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41820/tests/kraken/driver.html?testName=ai-astar) 20:42:25 INFO - PROCESS | 19419 | RSS: Main: 161845248 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | __start_tp_report 20:42:25 INFO - PROCESS | 19419 | _x_x_mozilla_page_load 20:42:25 INFO - PROCESS | 19419 | _x_x_mozilla_page_load_details 20:42:25 INFO - PROCESS | 19419 | |i|pagename|runs| 20:42:25 INFO - PROCESS | 19419 | |0;ai-astar;108;120;118;119;117;120;119;117;122;118 20:42:25 INFO - PROCESS | 19419 | |1;audio-beat-detection;155;126;128;162;133;128;130;128;155;128 20:42:25 INFO - PROCESS | 19419 | |2;audio-dft;166;163;166;172;166;169;167;164;165;162 20:42:25 INFO - PROCESS | 19419 | |3;audio-fft;78;78;78;118;78;78;77;78;105;79 20:42:25 INFO - PROCESS | 19419 | |4;audio-oscillator;85;86;104;84;85;84;105;85;84;109 20:42:25 INFO - PROCESS | 19419 | |5;imaging-gaussian-blur;114;115;113;114;114;118;114;115;114;114 20:42:25 INFO - PROCESS | 19419 | |6;imaging-darkroom;150;153;153;151;150;151;151;153;151;150 20:42:25 INFO - PROCESS | 19419 | |7;imaging-desaturate;97;96;96;97;96;96;95;96;96;96 20:42:25 INFO - PROCESS | 19419 | |8;json-parse-financial;67;66;67;66;67;68;68;68;69;66 20:42:25 INFO - PROCESS | 19419 | |9;json-stringify-tinderbox;65;67;65;66;66;66;66;65;65;66 20:42:25 INFO - PROCESS | 19419 | |10;stanford-crypto-aes;95;89;89;94;88;88;88;88;87;98 20:42:25 INFO - PROCESS | 19419 | |11;stanford-crypto-ccm;149;156;140;150;158;140;140;138;196;147 20:42:25 INFO - PROCESS | 19419 | |12;stanford-crypto-pbkdf2;180;189;180;183;182;187;185;187;199;181 20:42:25 INFO - PROCESS | 19419 | |13;stanford-crypto-sha256-iterative;75;74;82;72;71;71;71;70;73;70 20:42:25 INFO - PROCESS | 19419 | __end_tp_report 20:42:25 INFO - PROCESS | 19419 | __start_cc_report 20:42:25 INFO - PROCESS | 19419 | _x_x_mozilla_cycle_collect,875 20:42:25 INFO - PROCESS | 19419 | __end_cc_report 20:42:25 INFO - PROCESS | 19419 | __startTimestamp1487824945730__endTimestamp 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | ------- Summary: start ------- 20:42:25 INFO - PROCESS | 19419 | Number of tests: 14 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#0] ai-astar Cycles:10 Average:117.80 Median:119.00 stddev:3.77 (3.2%) stddev-sans-first:1.62 20:42:25 INFO - PROCESS | 19419 | Values: 108.0 120.0 118.0 119.0 117.0 120.0 119.0 117.0 122.0 118.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:131.50 stddev:14.07 (10.7%) stddev-sans-first:13.39 20:42:25 INFO - PROCESS | 19419 | Values: 155.0 126.0 128.0 162.0 133.0 128.0 130.0 128.0 155.0 128.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#2] audio-dft Cycles:10 Average:166.00 Median:166.00 stddev:2.91 (1.8%) stddev-sans-first:3.08 20:42:25 INFO - PROCESS | 19419 | Values: 166.0 163.0 166.0 172.0 166.0 169.0 167.0 164.0 165.0 162.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#3] audio-fft Cycles:10 Average:84.70 Median:78.00 stddev:14.46 (18.5%) stddev-sans-first:15.13 20:42:25 INFO - PROCESS | 19419 | Values: 78.0 78.0 78.0 118.0 78.0 78.0 77.0 78.0 105.0 79.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:84.50 stddev:10.38 (12.3%) stddev-sans-first:10.77 20:42:25 INFO - PROCESS | 19419 | Values: 85.0 86.0 104.0 84.0 85.0 84.0 105.0 85.0 84.0 109.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:114.00 stddev:1.35 (1.2%) stddev-sans-first:1.42 20:42:25 INFO - PROCESS | 19419 | Values: 114.0 115.0 113.0 114.0 114.0 118.0 114.0 115.0 114.0 114.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.00 stddev:1.25 (0.8%) stddev-sans-first:1.24 20:42:25 INFO - PROCESS | 19419 | Values: 150.0 153.0 153.0 151.0 150.0 151.0 151.0 153.0 151.0 150.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 20:42:25 INFO - PROCESS | 19419 | Values: 97.0 96.0 96.0 97.0 96.0 96.0 95.0 96.0 96.0 96.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:67.50 stddev:1.03 (1.5%) stddev-sans-first:1.09 20:42:25 INFO - PROCESS | 19419 | Values: 67.0 66.0 67.0 66.0 67.0 68.0 68.0 68.0 69.0 66.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 20:42:25 INFO - PROCESS | 19419 | Values: 65.0 67.0 65.0 66.0 66.0 66.0 66.0 65.0 65.0 66.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:89.00 stddev:3.81 (4.3%) stddev-sans-first:3.66 20:42:25 INFO - PROCESS | 19419 | Values: 95.0 89.0 89.0 94.0 88.0 88.0 88.0 88.0 87.0 98.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:149.50 stddev:17.16 (11.5%) stddev-sans-first:18.18 20:42:25 INFO - PROCESS | 19419 | Values: 149.0 156.0 140.0 150.0 158.0 140.0 140.0 138.0 196.0 147.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:186.00 stddev:5.76 (3.1%) stddev-sans-first:5.78 20:42:25 INFO - PROCESS | 19419 | Values: 180.0 189.0 180.0 183.0 182.0 187.0 185.0 187.0 199.0 181.0 20:42:25 INFO - PROCESS | 19419 | 20:42:25 INFO - PROCESS | 19419 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:72.50 stddev:3.60 (5.0%) stddev-sans-first:3.74 20:42:25 INFO - PROCESS | 19419 | Values: 75.0 74.0 82.0 72.0 71.0 71.0 71.0 70.0 73.0 70.0 20:42:25 INFO - PROCESS | 19419 | -------- Summary: end -------- 20:42:25 INFO - PROCESS | 19419 | 20:42:26 INFO - TEST-INFO | 19419: exit 0 20:42:27 INFO - TEST-OK | kraken | took 65325ms 20:42:27 INFO - SUITE-END | took 1185s 20:42:27 INFO - Completed test suite (00:19:45) 20:42:27 INFO - javascript benchmark 20:42:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6258.18906872318, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2520.9873950630245, 2536.5434221840073, 2542.1865003198973, 2552.8978840846366, 2557.6802899637532, 4469.4189755331845, 4515.683902126852, 4572.565606267408, 4598.954010459895, 4677.181493238687, 4503.0, 4525.909481810362, 4546.999660074786, 4576.5652263035045, 4596.793144308505, 4140.937885931706, 4198.118395137009, 4207.0, 4238.046439551092, 4276.166147601219, 4213.325867861143, 4240.491141063061, 4244.936325955113, 4257.40396344512, 4296.226679197744, 4081.5102187737366, 4105.835766569334, 4124.8556300529635, 4212.49450065991, 4230.957690423087, 4154.729942553724, 4194.182134483777, 4202.180574787918, 4235.724677895958, 4238.067625122499, 4175.0, 4185.707000509966, 4186.392973018896, 4207.242696314662, 4216.367544868263, 4347.0, 4364.301711726109, 4503.144402563503, 4535.523770004135, 4556.0, 4822.204336284509, 4856.2958371036, 4877.853664390074, 4905.681130726527, 4952.975235123838, 4911.066897289504, 4915.926261106087, 4918.926216106761, 4923.433805112386, 4951.381077365293, 4514.6388288936805, 4604.240300350438, 4604.332371806069, 4606.309053641913, 4642.2108241598335, 4943.876403089879, 4958.206686930074, 4983.950160498349, 4992.276119962585, 5092.439831618519, 4664.253719404946, 4710.552497512731, 4828.637852161102, 4840.29815676732, 4872.731999740048, 4954.380702412199, 4959.851204463872, 4974.253861920669, 5008.248762685554, 5031.496850315012, 4952.083864485082, 4954.232094025359, 4959.752012399365, 4965.4289756678445, 5011.724355160502, 4608.078384323148, 4704.811807527661, 4823.541763532458, 4848.757562121879, 4859.441164266084, 4174.582541745862, 4182.330827067654, 4183.769892655872, 4222.704410691284, 4225.260579398593, 3643.927121457556, 3647.2887786881834, 3648.6716195542, 3682.3003629310347, 3686.9631303687156, 4156.0, 4201.642860356904, 4205.978970105191, 4248.511421186603, 4276.2516559601945, 4934.753262336869, 4951.257311403318, 4964.851054468371, 4986.626003049786, 5012.423571289349, 4271.337942618899, 4276.700630955866, 4288.914221715549, 4325.178216138924, 4326.524082350938, 4275.315949448073, 4316.611504964505, 4434.711743736647, 4438.201123797683, 4441.866743997685, 4533.455985281853, 4542.727436353829, 4542.909141817211, 4551.271796512542, 4554.954450455453, 4851.733154676385, 4900.754962251945, 4939.580135688363, 4940.357753492023, 4978.576820970258, 4651.767411629473, 4655.0457156283555, 4656.8602941911795, 4687.32033855096, 4704.1062198182235, 4860.781264843161, 4865.56209941107, 4883.536064074049, 4898.779554920108, 4914.0908931847725, 4771.260454629538, 4822.228443449171, 4824.372831531879, 4989.725565093955, 5021.4225364083595, 4677.158111539956, 4708.340832283415, 4711.905761884812, 4714.646401519831, 4715.693479923932, 4718.197906355859, 4725.763711814327, 4727.290906363935, 4765.666403351732, 4771.856844294677, 4623.976880115578, 4625.283081122431, 4659.790309436151, 4672.042231342499, 4713.6936099153445, 4660.4174478190225, 4704.811807527661, 4763.476017638126, 4787.0904528139545, 4826.8793280168275, 4707.364505791811, 4720.551547602972, 4728.432588089451, 4729.69256998294, 4785.617150628011], "value": 4492.497944276202, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.036913924076, 543.8830651409946, 552.1359073050676, 555.9437069567823, 563.9323281206256, 36066.81966590167, 38069.80965095174, 38154.23691526168, 41353.58646413535, 41380.793096034475, 39948.20103597926, 40363.990900227436, 40380.394294085614, 40385.39421908674, 40388.0, 7595.126560445564, 7746.496477728958, 7753.534787912715, 7755.185705500898, 7763.573003484806, 7028.332308430691, 7175.9641201794175, 9509.429434233933, 9772.95113524435, 9800.166985806214, 9922.50387480623, 9968.75078123049, 9976.201903847712, 9995.95002024989, 9997.250206234525, 9816.312858099936, 9836.704898853046, 9895.604175832957, 9897.505124743733, 9905.2571057171, 9535.0, 9636.0, 9639.084286992724, 9669.951650241705, 9671.90328096717, 36350.454743178874, 36570.8171459141, 36617.0, 36669.0, 36959.815200924095, 3411.4882767584813, 3486.4421692529327, 3490.1972546314237, 3533.0460775590445, 3553.378158822222, 26870.46259074827, 27030.864845675842, 27187.728122718716, 27323.45353092927, 27582.862085689645, 3299.950500742491, 3481.3211423772373, 3501.002214368918, 3516.7010804081683, 3519.9120021999397, 229846.0, 231838.8408057966, 231887.0, 232079.0, 232133.0, 43431.56568434275, 44777.77611111924, 53482.73258633682, 53501.464985349645, 53501.73249133807, 51544.74227628838, 52936.470635293925, 52946.0, 53001.46998530043, 53215.733921330146, 12166.026717862549, 12393.070519711058, 12451.564195253122, 12495.812562811565, 12504.81242781359, 9188.540572971324, 9264.212541934012, 9279.350445468752, 9306.395084319498, 9315.161635452707, 15057.849421505864, 15261.465848695243, 15308.693826123415, 15377.0, 15544.76682849758, 6291.937080629135, 6297.2128483939505, 6365.204349456318, 6511.218653761579, 6673.699683514254, 751.8609057324412, 764.0, 776.2703059124404, 789.6959670526888, 794.9880751788612, 25030.0, 25827.74172258291, 26015.869920650654, 26238.0, 26276.605850912252, 901.6168128545341, 912.3112050402036, 915.0666320353203, 919.4575200631525, 926.8173451521704, 13601.0, 13787.172769633855, 14111.0, 14241.287935603386, 14252.0, 243370.0, 244974.0, 245606.0, 245763.0, 248146.0, 44576.3313550297, 44596.3310550342, 44650.10699785986, 44759.328610070224, 44769.32846007313, 1149.2816989381636, 1191.606769765975, 1195.6174024312136, 1201.3872924808236, 1210.6005018343926, 23626.64560031635, 24050.63924041106, 24664.50670986534, 24829.627555586318, 25062.37344066413, 36932.261354772214, 38516.80741596274, 38526.229475410895, 38551.0, 38589.42115868208, 172029.0, 172559.0, 172626.0, 172889.13555432143, 173073.0, 28618.427631446837, 29403.0, 32028.19929501781, 33632.66367336296, 35020.0, 39920.40119398153, 40154.0, 40276.3958540628, 41070.38394424026, 41146.38280425856, 34214.0, 34234.48648270228, 34400.82799585986, 34622.0, 35005.64994350024, 32422.513662295558, 32733.672663274014, 32753.34493310073, 33203.50194747032, 33584.42035642152, 33232.1691957703, 33712.49431258582, 33965.0, 33978.660213398536, 34066.65933340635, 24592.877035614707, 26522.60216096799, 28056.859715701292, 28381.148565543066, 29761.702382975895, 22312.553748924605, 22702.772972270726, 22820.20129295533, 23062.769372306062, 23068.0], "value": 16556.868323346273, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.1353415718338, 399.4527497328659, 399.9760014399136, 406.6726285340301, 425.09666957714853, 12526.686832829184, 16094.83905160948, 16337.101459419728, 17819.0, 17871.73192402115, 16846.157692115376, 17049.57376065599, 17406.216720247587, 17553.034583097924, 17568.385106521273, 5118.564921981636, 5349.491798279158, 5357.303550538424, 5366.221897824813, 5611.579131565129, 5724.370319264877, 5754.482096611304, 5756.0, 5759.625624334426, 5776.0, 5361.571074314065, 5614.831555053355, 5620.269364982567, 5733.455321744427, 5744.569157313218, 5672.9432705672825, 5705.400932902048, 5719.056355701305, 5752.165935939286, 6178.165947597106, 5585.720713964285, 5616.129499927518, 5616.747246373923, 5616.887662246732, 5631.746571404296, 12902.677433064155, 13117.344132793321, 13186.934065329708, 13568.59294221175, 13611.319434028357, 2238.552289542098, 2430.343807172054, 2488.365466805971, 2537.112010796225, 2557.475717477931, 9124.58939347731, 9242.075792420772, 9612.66355677555, 9905.059019393146, 9974.152197063258, 1660.867130629559, 1674.137819023204, 1680.8655307575486, 1685.8988460692399, 1710.683523548135, 75541.0, 78721.60639196825, 79289.60355198245, 80840.0, 81042.0, 10633.574657013633, 10680.78638427227, 10765.83851242232, 10882.23824332305, 11089.611863584898, 13601.523946661819, 15468.922655386876, 15498.612534686723, 15538.844611553739, 15575.299111540007, 6115.419035191739, 6254.966341324454, 6605.042268870987, 6636.13730215069, 6760.222574403908, 5710.7430165642645, 5737.77048918047, 5915.674637894957, 5928.822135335947, 5934.317553481319, 4244.108737165168, 4389.956100439019, 4457.2199865023495, 4461.107778444324, 4834.685745426536, 1869.906104928632, 1926.6050459656017, 1947.7857435682063, 2000.7699114602008, 2001.4395969128668, 776.1539921485495, 803.586153131138, 807.7617102954642, 810.2100452059312, 837.1461109668226, 17326.74009889853, 17375.913120434318, 17922.91038544825, 17970.640587188183, 18381.81618183828, 777.5801067423641, 789.5933594198992, 790.9683612655544, 792.9524228546305, 819.1685439279137, 9639.951800240953, 9776.51117444125, 9957.452340121363, 9991.600335986479, 10172.745681358025, 457261.0, 461271.0, 462726.0, 462821.0, 464079.0, 84434.5778271117, 86630.56684716536, 88750.279343481, 88838.55580722184, 89214.0, 891.5230351761776, 904.1500989070253, 908.6002159049997, 909.8043920557112, 913.6071489259549, 14889.0, 15262.08427494354, 15288.847111528743, 15772.369105236121, 15991.360345586047, 37325.44011839879, 37727.434088489244, 38355.42466863055, 38634.0, 38845.41731874081, 46176.7691161542, 48423.5157648419, 48974.0, 54645.45354546563, 55198.0, 7874.330681892104, 8135.186481351818, 8140.674373025013, 8468.66125354979, 8518.0, 11276.267042642203, 12271.509139634672, 12633.2420054797, 12785.488580456678, 13170.868291316965, 10421.374717516974, 10587.576496940035, 10603.681889543326, 11119.888801111885, 11206.775864482828, 10657.520411581343, 10782.838257425989, 10920.344779313267, 10930.070943969851, 11029.283096598696, 10226.6420675276, 10344.534495947852, 10692.358458492516, 11163.0, 11328.490217940378, 8085.5957202140835, 8166.714165004197, 8320.417570769989, 8356.080831108695, 8729.432586881832, 7706.229377062249, 9781.804363912825, 10516.369017858953, 10831.24181307301, 10949.616763413242], "value": 8401.938193851745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.4816045822358, 1359.1776974930167, 1363.884069854063, 1373.1349249972516, 1379.096691666958, 7460.403167746581, 7580.317771400573, 7761.301482866541, 7879.212078792118, 7896.8420631587405, 7894.6052697365185, 7980.481268717529, 8013.799655008627, 8035.075966263881, 8043.276105150535, 2068.9999999999964, 2097.622427962966, 2103.5161912760072, 2104.337133802855, 2106.536561956367, 2071.388213329397, 2094.413564202026, 2099.128861522466, 2101.53766171442, 2107.072887929306, 2020.4948762809297, 2048.6107639548436, 2053.4147767886157, 2054.311805545137, 2067.266120527214, 2036.5519585691197, 2049.1700861151257, 2054.2230970210735, 2054.753429588444, 2055.4553043443498, 1692.1200975492798, 1739.3564381179044, 1956.872803267783, 2000.3098930868828, 2052.445839623308, 7863.56750378729, 7896.012998375203, 7898.842023159562, 7906.248906353887, 7907.209279072104, 4314.935275970863, 4450.221211288012, 4461.442319710036, 4462.152191083716, 4475.977620111911, 2763.9447211055867, 2810.775137988956, 2826.434713057397, 2884.769218462518, 2899.5795609636693, 1030.062642994871, 1039.9220058495644, 1053.4732633683159, 1067.9733006674817, 1087.8205096159124, 10029.799404011952, 10196.643117490927, 10234.48827558619, 10269.178465722724, 10282.07461328484, 3803.82882770276, 3848.826802793881, 3964.32606456903, 4010.799460026987, 4021.0, 8142.592870356459, 8153.510789352657, 8162.877556836652, 8168.591570421455, 8178.959105204436, 4428.202923473806, 4453.0648563801315, 4456.532064133284, 4460.687751857404, 4461.866144015685, 4037.2127435149864, 4066.1461093170174, 4105.876823695234, 4121.093359460914, 4134.875953721393, 3278.9836050819595, 3344.6153692325693, 3371.6628337166576, 3379.2903490267317, 3384.1539615096226, 997.9301448898652, 997.9600815967425, 1006.5520843224841, 1013.8631284776608, 1013.9543720532594, 647.5240698086902, 668.4017804065335, 671.9798406047827, 672.7342699633617, 680.2415306932834, 4772.618190544748, 4822.397200349957, 4844.75776211188, 4905.632077594195, 4998.225275082368, 489.08052860622513, 504.684572142411, 505.54753495621213, 522.3288074823834, 547.5428017605344, 3370.191154122993, 3400.846961886721, 3441.4493681011418, 3447.7586568939932, 3601.3157500074904, 8147.470414423044, 8558.272546833465, 8659.13408659129, 9036.186743193013, 9176.082391760903, 9325.533723313807, 9372.625094996123, 9372.67195648149, 9375.437473751597, 9420.858687119699, 6567.080608714784, 6813.182418109858, 6908.447324214051, 6975.965120174366, 6988.19635741886, 5195.792168313225, 5340.118880384848, 5427.077396842626, 5546.75039623226, 5632.042552765957, 8233.83532329338, 8397.076321604742, 8471.745847624581, 8501.234888860032, 8777.868331974898, 8503.617337219963, 8525.616347264264, 8588.625819869041, 8588.699395521127, 8643.17889800468, 6563.442107420923, 6759.898601521079, 6848.178218613798, 6930.549514281555, 6970.407515361251, 7498.437617178624, 7515.173330933483, 7580.166181720117, 7610.695572177051, 7637.236276372319, 6718.798436046926, 6757.493188010821, 6885.862282754417, 6909.343612356818, 6943.305669433016, 6641.966790166019, 6710.698018589078, 6746.545983771458, 6899.931000689929, 6912.135983002125, 5830.183774271521, 5875.41245875409, 5929.6145750526075, 5952.0476723724, 5966.642001479925, 4546.590806827401, 4575.542445755398, 4590.196715574694, 4608.193566125981, 4725.787339569659, 4429.579189976947, 4514.571115743999, 4526.841560545365, 4587.197240482836, 4639.141758774637], "value": 3922.64100251292, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.51021631014436, 366.6388607221888, 369.1620022548813, 373.46594370050815, 377.6506731273573, 4911.7052976821415, 4976.4774698656665, 4979.427365852924, 4989.156180976845, 4998.650094493382, 4758.595519380848, 4881.755912204393, 4897.412310522734, 4906.1168989581865, 4929.86613078992, 3960.9801950990204, 3991.10866904767, 4000.7799571023656, 4004.579519150491, 4033.5361433435237, 3949.486566746333, 3991.846441311375, 4006.519217693881, 4010.598940105995, 4020.1758639478985, 3984.6413822755944, 3984.8605298814546, 3988.1425493518886, 3996.5403978542404, 4010.779407132621, 3917.5690674025836, 3919.3337132687225, 3924.7841368724708, 3976.582458841809, 4023.396490526415, 3573.5175751273505, 3573.94639080414, 3583.8566457341676, 3594.8382322795537, 3604.837782299777, 4862.197737373364, 4864.927026094611, 4870.537298956593, 4879.219085033973, 4881.4630390657, 4457.77711114443, 4561.771911404449, 4579.610733087691, 4594.95405045952, 4606.792694328729, 5180.922286165711, 5200.843974680765, 5214.8696282592855, 5248.370195576517, 5285.7621407036395, 3934.370500719871, 3960.702947278937, 3976.304146774332, 3985.262726395597, 3995.5405128410316, 4889.657723959324, 5258.211268309746, 5442.401335853053, 5455.863603409907, 5459.781608735646, 3937.1928754605237, 4019.457373254632, 4036.5761595032686, 4042.757434553936, 4050.736702114353, 4067.9389809152885, 4071.185762847442, 4095.7747323896874, 4115.341545352789, 4120.505539335299, 4561.589456948891, 4562.84030058953, 4571.817127314936, 4599.310103484504, 4611.123886461562, 5150.768215430315, 5245.7114858683135, 5282.577393808563, 5286.286351342519, 5316.9202461963105, 1384.5777038003505, 1401.3833913078213, 1401.3903951781008, 1406.810080639121, 1410.0975375759522, 954.6324665004023, 987.5506644476745, 992.6376872441507, 993.6820217530466, 1002.1631937332263, 4453.131639330364, 4462.174497717932, 4467.0, 4468.754218517947, 4497.257952437876, 5271.367435907716, 5297.060131626984, 5306.230596563477, 5310.150375939831, 5328.706921119298, 4296.742195468282, 4450.5549445055885, 4486.59620634138, 4497.740632622871, 4514.774261286922, 2756.476269508827, 2786.4427114577165, 2792.5252707039854, 2792.5392310268985, 2806.368567072433, 4897.632677549198, 4906.411230652273, 4913.1893237616105, 4913.287573301851, 4932.827351042768, 5120.820771272987, 5315.973420132875, 5335.893282134336, 5360.195970604441, 5374.462553744673, 4841.782119804618, 4866.829660961849, 4870.95129048705, 4883.316335713074, 4887.535684110004, 5216.113260745607, 5224.869378265574, 5238.97380513095, 5257.211418287287, 5276.419593844751, 4944.629152813625, 5126.871828204325, 5156.226566015128, 5196.376434827845, 5206.635535512478, 4883.8290659826735, 4893.916832395568, 4919.508049195052, 4920.753962301942, 4933.827316043921, 4000.239954408653, 4002.2795896737703, 4039.8384064637085, 4043.1913617276073, 4043.2317859606583, 5078.451072422947, 5121.436641969455, 5237.921431178612, 5274.813167037386, 5284.603654725834, 5100.38795344561, 5112.488751124858, 5119.590432765444, 5130.589552835839, 5166.2508936203285, 5025.522575355335, 5118.797082685492, 5125.43620201785, 5168.896622067613, 5178.32681751385, 4867.902641947212, 5078.238264260391, 5186.559142472781, 5220.451852555579, 5222.399424066281, 5296.179092240709, 5308.336457942757, 5321.3880403754065, 5332.520073193431, 5338.893222135458, 4997.8027246213705, 5186.351706036746, 5190.247414125006, 5231.293775340432, 5232.764525596282], "value": 3985.344150851223, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.8868003759321, 645.9192600924881, 701.7894631610519, 725.5030304241595, 758.1054355860086, 11860.110491713123, 12183.939080304586, 12256.203346782475, 12315.568955086574, 12340.938295308533, 12192.329421881816, 12860.871391286084, 12872.678183045406, 12877.806832897491, 13011.544595939144, 782.706485068098, 803.758872338299, 809.1382677448498, 812.0539571399313, 812.4881324765419, 786.9645865936018, 799.6921185343656, 814.6008455856647, 823.9917600824004, 825.6573521988367, 856.4775486952954, 858.459170722444, 858.4806192253691, 858.527809704663, 859.0121360435518, 841.8021764885247, 845.5687599324327, 846.8899043124385, 848.8981322241309, 850.8468475674376, 620.7206756959387, 626.4330780643525, 626.9090981807658, 626.937306269374, 627.1783963008473, 13132.0807543472, 13234.404451799595, 13240.73518529624, 13252.138610990254, 13510.324483775772, 11283.097352211804, 11469.713257168554, 12292.323922184276, 12731.363431828371, 13221.801672974912, 11569.479373428216, 11605.709857253552, 11734.589289374917, 11845.111616628701, 12155.878441215564, 3951.7826519541413, 4000.819963101638, 4049.3521036633997, 4069.918601627951, 4211.241976444238, 54925.0, 56559.0, 57210.14184787231, 58867.0, 59709.0, 9416.529173541296, 9476.763080923032, 9593.184579310697, 9680.515974201262, 9682.322237443312, 17776.0, 17892.46322610324, 18007.72988405175, 18156.63686726258, 18271.1777969989, 6095.725692343855, 6102.816915492543, 6116.082587611894, 6145.84635384119, 6192.040233763774, 3550.6626870447267, 3640.8543658253902, 3647.544056992876, 3662.047867554455, 3712.053426376257, 2968.1095671298526, 3016.909492715222, 3034.9544756828664, 3044.1476386611785, 3059.3116548776793, 508.76342500737337, 514.5703337712977, 522.908491014071, 528.6616565398148, 530.1888111189886, 536.5600207829542, 549.85978575463, 568.1165787200943, 569.7891780041411, 574.1789241384777, 7365.674178647895, 7747.147813740484, 7850.960745196352, 7950.284474397216, 8054.23484768946, 534.1453674121452, 542.4114835403552, 544.7004147718739, 554.2406902543541, 566.8356176708791, 3683.263347330491, 4269.807858646306, 4357.716748411343, 4537.115262523776, 4564.543545645409, 58144.709276453345, 60247.39752602505, 60318.69840650768, 60725.0, 63818.0, 51624.48375516197, 52240.21639675408, 54235.45764542383, 54672.72663636656, 54679.99999999921, 38645.03387415281, 46675.29987050197, 48016.27975580369, 48816.0, 49221.0, 8242.381821363302, 8762.167594078552, 8780.0, 8790.516521591373, 8999.865002024844, 32560.674393255766, 32944.0, 33848.1537961553, 34181.145471363416, 34285.828570857986, 40073.398899015956, 42838.78580607202, 43472.0, 43520.34719479147, 43865.12269754651, 10218.591256349662, 10316.535755890853, 10319.0, 10407.571683023098, 10491.108255798037, 11429.449038132672, 11547.162681543243, 11582.88417115818, 11858.288502689866, 11973.521059157536, 10599.682009539727, 10820.350778953289, 10972.506237219184, 11190.776184476428, 11446.515296580545, 11112.927920562472, 11189.510638403186, 11517.654470365902, 11537.866886112633, 11589.24669896454, 11164.944175279397, 11275.687017397418, 11408.926525084576, 11590.362530060927, 11769.764604708029, 9184.908150918673, 9492.95253523728, 9511.952440237754, 9580.377275477073, 9709.6601618943, 8634.78413039679, 8656.371154695646, 9167.77080572991, 9281.814363712552, 9351.5791789371], "value": 6148.279407969385, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 120.0, 118.0, 119.0, 117.0, 120.0, 119.0, 117.0, 122.0, 118.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 126.0, 128.0, 162.0, 133.0, 128.0, 130.0, 128.0, 155.0, 128.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 166.0, 172.0, 166.0, 169.0, 167.0, 164.0, 165.0, 162.0], "value": 166.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 118.0, 78.0, 78.0, 77.0, 78.0, 105.0, 79.0], "value": 84.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 104.0, 84.0, 85.0, 84.0, 105.0, 85.0, 84.0, 109.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 114.0, 114.0, 118.0, 114.0, 115.0, 114.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 153.0, 151.0, 150.0, 151.0, 151.0, 153.0, 151.0, 150.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 67.0, 68.0, 68.0, 68.0, 69.0, 66.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 89.0, 89.0, 94.0, 88.0, 88.0, 88.0, 88.0, 87.0, 98.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 156.0, 140.0, 150.0, 158.0, 140.0, 140.0, 138.0, 196.0, 147.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 189.0, 180.0, 183.0, 182.0, 187.0, 185.0, 187.0, 199.0, 181.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 82.0, 72.0, 71.0, 71.0, 71.0, 70.0, 73.0, 70.0], "value": 72.9, "unit": "score"}]}]} 20:42:27 INFO - Return code: 0 20:42:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:42:27 ERROR - # TBPL SUCCESS # 20:42:27 INFO - Running post-action listener: _resource_record_post_action 20:42:27 INFO - [mozharness: 2017-02-23 04:42:27.547504Z] Finished run-tests step (success) 20:42:27 INFO - Running post-run listener: _resource_record_post_run 20:42:27 INFO - Total resource usage - Wall time: 1201s; CPU: 14.0%; Read bytes: 4096; Write bytes: 360407040; Read time: 12; Write time: 486580 20:42:27 INFO - TinderboxPrint: CPU usage
13.5% 20:42:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 20:42:27 INFO - TinderboxPrint: I/O write bytes / time
360,407,040 / 486,580 20:42:27 INFO - TinderboxPrint: CPU idle
8,284.7 (86.4%) 20:42:27 INFO - TinderboxPrint: CPU user
1,238.5 (12.9%) 20:42:27 INFO - TinderboxPrint: Swap in / out
0 / 0 20:42:27 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 107294720; Read time: 0; Write time: 209828 20:42:27 INFO - run-tests - Wall time: 1186s; CPU: 14.0%; Read bytes: 4096; Write bytes: 253112320; Read time: 12; Write time: 276752 20:42:28 INFO - Running post-run listener: _upload_blobber_files 20:42:28 INFO - Blob upload gear active. 20:42:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:42:28 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 20:42:28 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'] 20:42:28 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 20:42:28 INFO - (blobuploader) - INFO - Open directory for files ... 20:42:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:42:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:42:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:29 INFO - (blobuploader) - INFO - Done attempting. 20:42:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:42:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:42:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:30 INFO - (blobuploader) - INFO - Done attempting. 20:42:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:42:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:42:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:30 INFO - (blobuploader) - INFO - Done attempting. 20:42:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:42:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:42:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:35 INFO - (blobuploader) - INFO - Done attempting. 20:42:35 INFO - (blobuploader) - INFO - Iteration through files over. 20:42:35 INFO - Return code: 0 20:42:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:42:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:42:35 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6b33a580c83a208cc16b871e1cbda615c03129c53a8267374dc068918d6e22d097140c66b78d59aadf2e9e90ee2809500ca6f2093ebf2d65aec98d666e0c08ba", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e1546158633e4e433212f9a58af076b03ee1dc290f9af821c17aaff1ea5f641f534ee23ae88d9d7f5f0ddaf56767a19b38f3023238e5144e54b7c4572111d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9461fef49282a2e6bb54aecb5ceb258892cce902ae748638c325bba9266e6c9e1a78a61cab57366edd61974bcc035e71a876b91e2853298c3e53b2cd7838cc2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dff15c56f0f35460715d413a1039ca46a34ef2fdfa43cec623fc3ff6883f80afef978a953dcc6b3a5f32833d534fde7d07660acbbfdaad7b5f775ce5d03c8431"} 20:42:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:42:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:42:35 INFO - Contents: 20:42:35 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6b33a580c83a208cc16b871e1cbda615c03129c53a8267374dc068918d6e22d097140c66b78d59aadf2e9e90ee2809500ca6f2093ebf2d65aec98d666e0c08ba", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e1546158633e4e433212f9a58af076b03ee1dc290f9af821c17aaff1ea5f641f534ee23ae88d9d7f5f0ddaf56767a19b38f3023238e5144e54b7c4572111d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9461fef49282a2e6bb54aecb5ceb258892cce902ae748638c325bba9266e6c9e1a78a61cab57366edd61974bcc035e71a876b91e2853298c3e53b2cd7838cc2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dff15c56f0f35460715d413a1039ca46a34ef2fdfa43cec623fc3ff6883f80afef978a953dcc6b3a5f32833d534fde7d07660acbbfdaad7b5f775ce5d03c8431"} 20:42:35 INFO - Running post-run listener: copy_logs_to_upload_dir 20:42:35 INFO - Copying logs to upload dir... 20:42:35 INFO - mkdir: /builds/slave/test/build/upload/logs 20:42:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.632195 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-02-22 20:42:34.634686) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:34.638923) ========= 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=ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677 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/6b33a580c83a208cc16b871e1cbda615c03129c53a8267374dc068918d6e22d097140c66b78d59aadf2e9e90ee2809500ca6f2093ebf2d65aec98d666e0c08ba", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e1546158633e4e433212f9a58af076b03ee1dc290f9af821c17aaff1ea5f641f534ee23ae88d9d7f5f0ddaf56767a19b38f3023238e5144e54b7c4572111d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9461fef49282a2e6bb54aecb5ceb258892cce902ae748638c325bba9266e6c9e1a78a61cab57366edd61974bcc035e71a876b91e2853298c3e53b2cd7838cc2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dff15c56f0f35460715d413a1039ca46a34ef2fdfa43cec623fc3ff6883f80afef978a953dcc6b3a5f32833d534fde7d07660acbbfdaad7b5f775ce5d03c8431"} build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011234 build_url: 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6b33a580c83a208cc16b871e1cbda615c03129c53a8267374dc068918d6e22d097140c66b78d59aadf2e9e90ee2809500ca6f2093ebf2d65aec98d666e0c08ba", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e1546158633e4e433212f9a58af076b03ee1dc290f9af821c17aaff1ea5f641f534ee23ae88d9d7f5f0ddaf56767a19b38f3023238e5144e54b7c4572111d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9461fef49282a2e6bb54aecb5ceb258892cce902ae748638c325bba9266e6c9e1a78a61cab57366edd61974bcc035e71a876b91e2853298c3e53b2cd7838cc2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dff15c56f0f35460715d413a1039ca46a34ef2fdfa43cec623fc3ff6883f80afef978a953dcc6b3a5f32833d534fde7d07660acbbfdaad7b5f775ce5d03c8431"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:34.668333) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:34.668640) ========= 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=ba164b0a7b0ec7655860e956000001c1-1487823245.455572-1229145677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004704 ========= master_lag: 0.69 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:35.367179) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:42:35.367465) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:42:35.368228) ========= ========= Total master_lag: 0.79 =========