builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1487371194.28 results: success (0) revision: d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:54.279690) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:54.280174) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:54.280484) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:54.297199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:54.297618) ========= 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=8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 14:39:54-- 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% 226M=0s 2017-02-17 14:39:54 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129663 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:54.440880) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:54.441207) ========= 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=8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020711 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:54.491270) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:54.491635) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 --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 d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 --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=8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 14:39:54,799 truncating revision to first 12 chars 2017-02-17 14:39:54,799 Setting DEBUG logging. 2017-02-17 14:39:54,799 attempt 1/10 2017-02-17 14:39:54,799 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d11c29c1db3a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 14:39:55,336 unpacking tar archive at: mozilla-central-d11c29c1db3a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.862618 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:55.367500) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:55.367827) ========= script_repo_revision: d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:55.368200) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:55.368484) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 14:39:55.377763) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-02-17 14:39:55.379011) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:39:55 INFO - MultiFileLogger online at 20170217 14:39:55 in /builds/slave/test 14:39:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:39:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:39:55 INFO - {'append_to_log': False, 14:39:55 INFO - 'base_work_dir': '/builds/slave/test', 14:39:55 INFO - 'blob_upload_branch': 'Firefox', 14:39:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:39:55 INFO - 'branch': 'Firefox', 14:39:55 INFO - 'buildbot_json_path': 'buildprops.json', 14:39:55 INFO - 'config_files': ('talos/linux_config.py',), 14:39:55 INFO - 'default_actions': ('clobber', 14:39:55 INFO - 'read-buildbot-config', 14:39:55 INFO - 'download-and-extract', 14:39:55 INFO - 'populate-webroot', 14:39:55 INFO - 'create-virtualenv', 14:39:55 INFO - 'install', 14:39:55 INFO - 'run-tests'), 14:39:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:39:55 INFO - 'download_minidump_stackwalk': True, 14:39:55 INFO - 'download_symbols': 'ondemand', 14:39:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:39:55 INFO - 'tooltool.py': '/tools/tooltool.py', 14:39:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:39:55 INFO - '/tools/misc-python/virtualenv.py')}, 14:39:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:39:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:39:55 INFO - 'installer_path': 'installer.exe', 14:39:55 INFO - 'log_level': 'info', 14:39:55 INFO - 'log_name': 'talos', 14:39:55 INFO - 'log_to_console': True, 14:39:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:39:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:39:55 INFO - 'opt_config_files': (), 14:39:55 INFO - 'pip_index': False, 14:39:55 INFO - 'sps_profile': False, 14:39:55 INFO - 'sps_profile_interval': 0, 14:39:55 INFO - 'suite': 'dromaeojs', 14:39:55 INFO - 'system_bits': '32', 14:39:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:39:55 INFO - 'title': 'talos-linux64-ix-001', 14:39:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:39:55 INFO - 'use_talos_json': True, 14:39:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:39:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:39:55 INFO - 'work_dir': 'build'} 14:39:55 INFO - [mozharness: 2017-02-17 22:39:55.791541Z] Running clobber step. 14:39:55 INFO - Running pre-action listener: _resource_record_pre_action 14:39:55 INFO - Running main action method: clobber 14:39:55 INFO - rmtree: /builds/slave/test/build 14:39:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:39:56 INFO - Running post-action listener: _resource_record_post_action 14:39:56 INFO - [mozharness: 2017-02-17 22:39:56.697337Z] Finished clobber step (success) 14:39:56 INFO - [mozharness: 2017-02-17 22:39:56.697438Z] Running read-buildbot-config step. 14:39:56 INFO - Running pre-action listener: _resource_record_pre_action 14:39:56 INFO - Running main action method: read_buildbot_config 14:39:56 INFO - Using buildbot properties: 14:39:56 INFO - { 14:39:56 INFO - "project": "", 14:39:56 INFO - "product": "firefox", 14:39:56 INFO - "who": "kwierso@gmail.com", 14:39:56 INFO - "installer_path": "public/build/target.tar.bz2", 14:39:56 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 14:39:56 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:39:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 14:39:56 INFO - "stage_platform": "linux64-pgo", 14:39:56 INFO - "basedir": "/builds/slave/test", 14:39:56 INFO - "buildnumber": 39, 14:39:56 INFO - "platform": "ubuntu64_hw", 14:39:56 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:39:56 INFO - "slavebuilddir": "test", 14:39:56 INFO - "taskId": "IKlQ8QZ9RKSVKb2s--bNdA", 14:39:56 INFO - "branch": "mozilla-central", 14:39:56 INFO - "script_repo_revision": "production", 14:39:56 INFO - "revision": "d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85", 14:39:56 INFO - "slavename": "talos-linux64-ix-001", 14:39:56 INFO - "repo_path": "mozilla-central" 14:39:56 INFO - } 14:39:56 INFO - Finding installer, test and symbols from parent task. 14:39:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IKlQ8QZ9RKSVKb2s--bNdA'}, attempt #1 14:39:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IKlQ8QZ9RKSVKb2s--bNdA'}, attempt #1 14:39:57 INFO - Task dependencies: Czym9kfRT02cFUT4KThoJw 14:39:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw'}, attempt #1 14:39:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.tar.bz2 14:39:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/test_packages.json 14:39:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.crashreporter-symbols.zip 14:39:58 INFO - Running post-action listener: _resource_record_post_action 14:39:58 INFO - [mozharness: 2017-02-17 22:39:58.295933Z] Finished read-buildbot-config step (success) 14:39:58 INFO - [mozharness: 2017-02-17 22:39:58.296153Z] Running download-and-extract step. 14:39:58 INFO - Running pre-action listener: _resource_record_pre_action 14:39:58 INFO - Running main action method: download_and_extract 14:39:58 INFO - mkdir: /builds/slave/test/build/tests 14:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:39:58 INFO - trying https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.test_packages.json 14:39:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:39:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:39:58 INFO - Downloaded 896 bytes. 14:39:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:39:58 INFO - Using the following test package requirements: 14:39:58 INFO - {u'common': [u'target.common.tests.zip'], 14:39:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:39:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:39:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:39:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:39:58 INFO - u'mozbase': [u'target.common.tests.zip'], 14:39:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:39:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:39:58 INFO - u'web-platform': [u'target.common.tests.zip', 14:39:58 INFO - u'target.web-platform.tests.zip'], 14:39:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:39:58 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:39:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.common.tests.zip 14:39:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:39:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.common.tests.zip into memory 14:40:00 INFO - Expected file size: 59221477 14:40:00 INFO - Obtained file size: 59221477 14:40:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:40:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.common.tests.zip 14:40:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:40:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.common.tests.zip into memory 14:40:10 INFO - Expected file size: 59221477 14:40:11 INFO - Obtained file size: 59221477 14:40:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.talos.tests.zip 14:40:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:40:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.talos.tests.zip into memory 14:40:22 INFO - Expected file size: 14403647 14:40:22 INFO - Obtained file size: 14403647 14:40:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:23 INFO - trying https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.tar.bz2 14:40:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:40:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:40:24 INFO - Downloaded 66732731 bytes. 14:40:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.tar.bz2 14:40:24 INFO - mkdir: /builds/slave/test/properties 14:40:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:40:24 INFO - Writing to file /builds/slave/test/properties/build_url 14:40:24 INFO - Contents: 14:40:24 INFO - build_url:https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.tar.bz2 14:40:24 INFO - Running post-action listener: _resource_record_post_action 14:40:24 INFO - Running post-action listener: set_extra_try_arguments 14:40:24 INFO - [mozharness: 2017-02-17 22:40:24.171751Z] Finished download-and-extract step (success) 14:40:24 INFO - [mozharness: 2017-02-17 22:40:24.171848Z] Running populate-webroot step. 14:40:24 INFO - Running pre-action listener: _resource_record_pre_action 14:40:24 INFO - Running main action method: populate_webroot 14:40:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:40:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:40:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:40:24 INFO - '--tppagecycles', 14:40:24 INFO - '7'], 14:40:24 INFO - 'tests': ['tsvgm']}}, 14:40:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:40:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:40:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:40:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:40:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:40:24 INFO - 'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:40:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:40:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:40:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:40:24 INFO - 'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['damp', 'tps']}, 14:40:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:40:24 INFO - 'tests': ['damp', 'tps']}, 14:40:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['dromaeo_dom']}, 14:40:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:40:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['basic_compositor_video']}, 14:40:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:40:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['a11yr', 14:40:24 INFO - 'ts_paint', 14:40:24 INFO - 'tpaint', 14:40:24 INFO - 'sessionrestore', 14:40:24 INFO - 'sessionrestore_no_auto_restore', 14:40:24 INFO - 'tabpaint']}, 14:40:24 INFO - 'other-e10s': {'tests': ['a11yr', 14:40:24 INFO - 'ts_paint', 14:40:24 INFO - 'tpaint', 14:40:24 INFO - 'sessionrestore', 14:40:24 INFO - 'sessionrestore_no_auto_restore', 14:40:24 INFO - 'tabpaint']}, 14:40:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:40:24 INFO - 'ts_paint', 14:40:24 INFO - 'tpaint', 14:40:24 INFO - 'sessionrestore', 14:40:24 INFO - 'sessionrestore_no_auto_restore', 14:40:24 INFO - 'tabpaint']}, 14:40:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:40:24 INFO - 'ts_paint', 14:40:24 INFO - 'tpaint', 14:40:24 INFO - 'sessionrestore', 14:40:24 INFO - 'sessionrestore_no_auto_restore', 14:40:24 INFO - 'tabpaint']}, 14:40:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['a11yr', 14:40:24 INFO - 'ts_paint', 14:40:24 INFO - 'tpaint', 14:40:24 INFO - 'sessionrestore', 14:40:24 INFO - 'sessionrestore_no_auto_restore', 14:40:24 INFO - 'tabpaint']}, 14:40:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['a11yr', 14:40:24 INFO - 'ts_paint', 14:40:24 INFO - 'tpaint', 14:40:24 INFO - 'sessionrestore', 14:40:24 INFO - 'sessionrestore_no_auto_restore', 14:40:24 INFO - 'tabpaint']}, 14:40:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['tsvgx', 14:40:24 INFO - 'tsvgr_opacity', 14:40:24 INFO - 'tart', 14:40:24 INFO - 'tscrollx', 14:40:24 INFO - 'cart', 14:40:24 INFO - 'tsvg_static']}, 14:40:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:40:24 INFO - 'tsvgr_opacity', 14:40:24 INFO - 'tart', 14:40:24 INFO - 'tscrollx', 14:40:24 INFO - 'cart', 14:40:24 INFO - 'tsvg_static']}, 14:40:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:40:24 INFO - 'talos_options': ['--disable-e10s'], 14:40:24 INFO - 'tests': ['tp5o']}, 14:40:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:40:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:40:24 INFO - 'talos_options': ['--disable-e10s', 14:40:24 INFO - '--xperf_path', 14:40:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:40:24 INFO - 'tests': ['tp5n']}, 14:40:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:40:24 INFO - 'talos_options': ['--xperf_path', 14:40:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:40:24 INFO - 'tests': ['tp5n']}}, 14:40:24 INFO - 'talos.zip': {'path': '', 14:40:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:40:24 INFO - Running post-action listener: _resource_record_post_action 14:40:24 INFO - [mozharness: 2017-02-17 22:40:24.184991Z] Finished populate-webroot step (success) 14:40:24 INFO - [mozharness: 2017-02-17 22:40:24.185096Z] Running create-virtualenv step. 14:40:24 INFO - Running pre-action listener: _resource_record_pre_action 14:40:24 INFO - Running main action method: create_virtualenv 14:40:24 INFO - Creating virtualenv /builds/slave/test/build/venv 14:40:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:40:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:40:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:40:24 INFO - Using real prefix '/usr' 14:40:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:40:24 INFO - Installing distribute.............................................................................................................................................................................................done. 14:40:26 INFO - Installing pip.................done. 14:40:26 INFO - Return code: 0 14:40:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:40:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:40:26 INFO - Reading from file tmpfile_stdout 14:40:26 INFO - Output received: 14:40:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:40:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14a51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1745ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, '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': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:40:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:40:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:40:26 INFO - Using env: {'DISPLAY': ':0', 14:40:26 INFO - 'HOME': '/home/cltbld', 14:40:26 INFO - 'LANG': 'en_US.UTF-8', 14:40:26 INFO - 'LANGUAGE': 'en_US:en', 14:40:26 INFO - 'LOGNAME': 'cltbld', 14:40:26 INFO - 'MAIL': '/var/mail/cltbld', 14:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:40:26 INFO - 'MOZ_NO_REMOTE': '1', 14:40:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:40:26 INFO - 'NO_EM_RESTART': '1', 14:40:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:40:26 INFO - 'PWD': '/builds/slave/test', 14:40:26 INFO - 'SHELL': '/bin/bash', 14:40:26 INFO - 'SHLVL': '1', 14:40:26 INFO - 'TERM': 'linux', 14:40:26 INFO - 'TMOUT': '86400', 14:40:26 INFO - 'USER': 'cltbld', 14:40:26 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:40:26 INFO - '_': '/tools/buildbot/bin/python'} 14:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:40:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:40:27 INFO - Cleaning up... 14:40:27 INFO - Return code: 0 14:40:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:40:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:40:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:27 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 0x14a51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1745ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, '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': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:40:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:40:27 INFO - Using env: {'DISPLAY': ':0', 14:40:27 INFO - 'HOME': '/home/cltbld', 14:40:27 INFO - 'LANG': 'en_US.UTF-8', 14:40:27 INFO - 'LANGUAGE': 'en_US:en', 14:40:27 INFO - 'LOGNAME': 'cltbld', 14:40:27 INFO - 'MAIL': '/var/mail/cltbld', 14:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:40:27 INFO - 'MOZ_NO_REMOTE': '1', 14:40:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:40:27 INFO - 'NO_EM_RESTART': '1', 14:40:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:40:27 INFO - 'PWD': '/builds/slave/test', 14:40:27 INFO - 'SHELL': '/bin/bash', 14:40:27 INFO - 'SHLVL': '1', 14:40:27 INFO - 'TERM': 'linux', 14:40:27 INFO - 'TMOUT': '86400', 14:40:27 INFO - 'USER': 'cltbld', 14:40:27 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:40:27 INFO - '_': '/tools/buildbot/bin/python'} 14:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:40:27 INFO - Downloading/unpacking psutil>=3.1.1 14:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:40:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:40:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:40:29 INFO - Installing collected packages: psutil 14:40:29 INFO - Running setup.py install for psutil 14:40:29 INFO - building 'psutil._psutil_linux' extension 14:40:29 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:40:29 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:40:29 INFO - building 'psutil._psutil_posix' extension 14:40:29 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:40:29 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:40:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:40:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:40:30 INFO - Successfully installed psutil 14:40:30 INFO - Cleaning up... 14:40:30 INFO - Return code: 0 14:40:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:40:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:40:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:40:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:30 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 0x14a51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1745ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, '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': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:40:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:40:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:40:30 INFO - Using env: {'DISPLAY': ':0', 14:40:30 INFO - 'HOME': '/home/cltbld', 14:40:30 INFO - 'LANG': 'en_US.UTF-8', 14:40:30 INFO - 'LANGUAGE': 'en_US:en', 14:40:30 INFO - 'LOGNAME': 'cltbld', 14:40:30 INFO - 'MAIL': '/var/mail/cltbld', 14:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:40:30 INFO - 'MOZ_NO_REMOTE': '1', 14:40:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:40:30 INFO - 'NO_EM_RESTART': '1', 14:40:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:40:30 INFO - 'PWD': '/builds/slave/test', 14:40:30 INFO - 'SHELL': '/bin/bash', 14:40:30 INFO - 'SHLVL': '1', 14:40:30 INFO - 'TERM': 'linux', 14:40:30 INFO - 'TMOUT': '86400', 14:40:30 INFO - 'USER': 'cltbld', 14:40:30 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:40:30 INFO - '_': '/tools/buildbot/bin/python'} 14:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:40:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:40:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:40:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:40:32 INFO - Installing collected packages: mozsystemmonitor 14:40:32 INFO - Running setup.py install for mozsystemmonitor 14:40:32 INFO - Successfully installed mozsystemmonitor 14:40:32 INFO - Cleaning up... 14:40:32 INFO - Return code: 0 14:40:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:40: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')]} 14:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:40:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40: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')]} 14:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:40:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:32 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 0x14a51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1745ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, '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': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:40:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:40:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:40:32 INFO - Using env: {'DISPLAY': ':0', 14:40:32 INFO - 'HOME': '/home/cltbld', 14:40:32 INFO - 'LANG': 'en_US.UTF-8', 14:40:32 INFO - 'LANGUAGE': 'en_US:en', 14:40:32 INFO - 'LOGNAME': 'cltbld', 14:40:32 INFO - 'MAIL': '/var/mail/cltbld', 14:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:40:32 INFO - 'MOZ_NO_REMOTE': '1', 14:40:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:40:32 INFO - 'NO_EM_RESTART': '1', 14:40:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:40:32 INFO - 'PWD': '/builds/slave/test', 14:40:32 INFO - 'SHELL': '/bin/bash', 14:40:32 INFO - 'SHLVL': '1', 14:40:32 INFO - 'TERM': 'linux', 14:40:32 INFO - 'TMOUT': '86400', 14:40:32 INFO - 'USER': 'cltbld', 14:40:32 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:40:32 INFO - '_': '/tools/buildbot/bin/python'} 14:40:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:40:32 INFO - Downloading/unpacking jsonschema==2.5.1 14:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:40:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:34 INFO - Downloading functools32-3.2.3-2.tar.gz 14:40:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:40:34 INFO - warning: no files found matching '*.txt' 14:40:34 INFO - no previously-included directories found matching 'build' 14:40:34 INFO - no previously-included directories found matching 'dist' 14:40:34 INFO - no previously-included directories found matching '.git*' 14:40:35 INFO - Installing collected packages: jsonschema, functools32 14:40:35 INFO - Running setup.py install for functools32 14:40:35 INFO - warning: no files found matching '*.txt' 14:40:35 INFO - no previously-included directories found matching 'build' 14:40:35 INFO - no previously-included directories found matching 'dist' 14:40:35 INFO - no previously-included directories found matching '.git*' 14:40:35 INFO - Successfully installed jsonschema functools32 14:40:35 INFO - Cleaning up... 14:40:35 INFO - Return code: 0 14:40:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:35 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 0x14a51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1745ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, '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': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:40:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:40:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:40:35 INFO - Using env: {'DISPLAY': ':0', 14:40:35 INFO - 'HOME': '/home/cltbld', 14:40:35 INFO - 'LANG': 'en_US.UTF-8', 14:40:35 INFO - 'LANGUAGE': 'en_US:en', 14:40:35 INFO - 'LOGNAME': 'cltbld', 14:40:35 INFO - 'MAIL': '/var/mail/cltbld', 14:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:40:35 INFO - 'MOZ_NO_REMOTE': '1', 14:40:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:40:35 INFO - 'NO_EM_RESTART': '1', 14:40:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:40:35 INFO - 'PWD': '/builds/slave/test', 14:40:35 INFO - 'SHELL': '/bin/bash', 14:40:35 INFO - 'SHLVL': '1', 14:40:35 INFO - 'TERM': 'linux', 14:40:35 INFO - 'TMOUT': '86400', 14:40:35 INFO - 'USER': 'cltbld', 14:40:35 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:40:35 INFO - '_': '/tools/buildbot/bin/python'} 14:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:40:35 INFO - Cleaning up... 14:40:35 INFO - Return code: 0 14:40:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:35 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 0x14a51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1745ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, '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': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:40:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:40:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:40:35 INFO - Using env: {'DISPLAY': ':0', 14:40:35 INFO - 'HOME': '/home/cltbld', 14:40:35 INFO - 'LANG': 'en_US.UTF-8', 14:40:35 INFO - 'LANGUAGE': 'en_US:en', 14:40:35 INFO - 'LOGNAME': 'cltbld', 14:40:35 INFO - 'MAIL': '/var/mail/cltbld', 14:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:40:35 INFO - 'MOZ_NO_REMOTE': '1', 14:40:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:40:35 INFO - 'NO_EM_RESTART': '1', 14:40:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:40:35 INFO - 'PWD': '/builds/slave/test', 14:40:35 INFO - 'SHELL': '/bin/bash', 14:40:35 INFO - 'SHLVL': '1', 14:40:35 INFO - 'TERM': 'linux', 14:40:35 INFO - 'TMOUT': '86400', 14:40:35 INFO - 'USER': 'cltbld', 14:40:35 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:40:35 INFO - '_': '/tools/buildbot/bin/python'} 14:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:40:35 INFO - Downloading/unpacking blobuploader==1.2.4 14:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:37 INFO - Downloading blobuploader-1.2.4.tar.gz 14:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:40:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:40:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:38 INFO - Downloading docopt-0.6.1.tar.gz 14:40:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:40:38 INFO - Installing collected packages: blobuploader, requests, docopt 14:40:38 INFO - Running setup.py install for blobuploader 14:40:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:40:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:40:38 INFO - Running setup.py install for requests 14:40:38 INFO - Running setup.py install for docopt 14:40:38 INFO - Successfully installed blobuploader requests docopt 14:40:38 INFO - Cleaning up... 14:40:39 INFO - Return code: 0 14:40:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:39 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 0x14a51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1745ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, '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': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:40:39 INFO - Using env: {'DISPLAY': ':0', 14:40:39 INFO - 'HOME': '/home/cltbld', 14:40:39 INFO - 'LANG': 'en_US.UTF-8', 14:40:39 INFO - 'LANGUAGE': 'en_US:en', 14:40:39 INFO - 'LOGNAME': 'cltbld', 14:40:39 INFO - 'MAIL': '/var/mail/cltbld', 14:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:40:39 INFO - 'MOZ_NO_REMOTE': '1', 14:40:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:40:39 INFO - 'NO_EM_RESTART': '1', 14:40:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:40:39 INFO - 'PWD': '/builds/slave/test', 14:40:39 INFO - 'SHELL': '/bin/bash', 14:40:39 INFO - 'SHLVL': '1', 14:40:39 INFO - 'TERM': 'linux', 14:40:39 INFO - 'TMOUT': '86400', 14:40:39 INFO - 'USER': 'cltbld', 14:40:39 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:40:39 INFO - '_': '/tools/buildbot/bin/python'} 14:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:40:39 INFO - Running setup.py (path:/tmp/pip-6Y1S14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:40:39 INFO - Running setup.py (path:/tmp/pip-KuI7dQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:40:39 INFO - Running setup.py (path:/tmp/pip-cnMxMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:40:39 INFO - Running setup.py (path:/tmp/pip-U7DAmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:40:39 INFO - Running setup.py (path:/tmp/pip-gqDyOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:40:39 INFO - Running setup.py (path:/tmp/pip-H_W2ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:40:39 INFO - Running setup.py (path:/tmp/pip-oFcc_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:40:39 INFO - Running setup.py (path:/tmp/pip-yrJBoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:40:39 INFO - Running setup.py (path:/tmp/pip-gJ0Rsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:40:39 INFO - Running setup.py (path:/tmp/pip-eVwCvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:40:39 INFO - Running setup.py (path:/tmp/pip-aICFlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:40:40 INFO - Running setup.py (path:/tmp/pip-DU65PY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:40:40 INFO - Running setup.py (path:/tmp/pip-ihbLWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:40:40 INFO - Running setup.py (path:/tmp/pip-liVx_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:40:40 INFO - Running setup.py (path:/tmp/pip-45oNcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:40:40 INFO - Running setup.py (path:/tmp/pip-w6FtOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:40:40 INFO - Running setup.py (path:/tmp/pip-7Gcc9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:40:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:40:40 INFO - Running setup.py install for manifestparser 14:40:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:40:40 INFO - Running setup.py install for mozcrash 14:40:40 INFO - Running setup.py install for mozdebug 14:40:40 INFO - Running setup.py install for mozdevice 14:40:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:40:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:40:40 INFO - Running setup.py install for mozfile 14:40:41 INFO - Running setup.py install for mozhttpd 14:40:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Running setup.py install for mozinfo 14:40:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Running setup.py install for mozInstall 14:40:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Running setup.py install for mozleak 14:40:41 INFO - Running setup.py install for mozlog 14:40:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Running setup.py install for moznetwork 14:40:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Running setup.py install for mozprocess 14:40:41 INFO - Running setup.py install for mozprofile 14:40:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:40:41 INFO - Running setup.py install for mozrunner 14:40:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:40:42 INFO - Running setup.py install for mozscreenshot 14:40:42 INFO - Running setup.py install for moztest 14:40:42 INFO - Running setup.py install for mozversion 14:40:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:40:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:40:42 INFO - Cleaning up... 14:40:42 INFO - Return code: 0 14:40:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:40:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:40:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:42 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 0x14a51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1745ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, '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': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:40:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:40:42 INFO - Using env: {'DISPLAY': ':0', 14:40:42 INFO - 'HOME': '/home/cltbld', 14:40:42 INFO - 'LANG': 'en_US.UTF-8', 14:40:42 INFO - 'LANGUAGE': 'en_US:en', 14:40:42 INFO - 'LOGNAME': 'cltbld', 14:40:42 INFO - 'MAIL': '/var/mail/cltbld', 14:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:40:42 INFO - 'MOZ_NO_REMOTE': '1', 14:40:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:40:42 INFO - 'NO_EM_RESTART': '1', 14:40:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:40:42 INFO - 'PWD': '/builds/slave/test', 14:40:42 INFO - 'SHELL': '/bin/bash', 14:40:42 INFO - 'SHLVL': '1', 14:40:42 INFO - 'TERM': 'linux', 14:40:42 INFO - 'TMOUT': '86400', 14:40:42 INFO - 'USER': 'cltbld', 14:40:42 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:40:42 INFO - '_': '/tools/buildbot/bin/python'} 14:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:40:42 INFO - Running setup.py (path:/tmp/pip-f1OMyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:40:42 INFO - Running setup.py (path:/tmp/pip-viOd0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:40:42 INFO - Running setup.py (path:/tmp/pip-3JPP42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:40:42 INFO - Running setup.py (path:/tmp/pip-sNoxBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:40:42 INFO - Running setup.py (path:/tmp/pip-pdc9D_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:40:42 INFO - Running setup.py (path:/tmp/pip-gct4qT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:40:42 INFO - Running setup.py (path:/tmp/pip-09spOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:40:43 INFO - Running setup.py (path:/tmp/pip-hAtdKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:40:43 INFO - Running setup.py (path:/tmp/pip-SzgWNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:40:43 INFO - Running setup.py (path:/tmp/pip-6zkhfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:40:43 INFO - Running setup.py (path:/tmp/pip-rLxbGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:40:43 INFO - Running setup.py (path:/tmp/pip-ZUZ0rX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:40:43 INFO - Running setup.py (path:/tmp/pip-MB97Tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:40:43 INFO - Running setup.py (path:/tmp/pip-gZVhNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:40:43 INFO - Running setup.py (path:/tmp/pip-fM9Fmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:40:43 INFO - Running setup.py (path:/tmp/pip-u4PQrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:40:43 INFO - Running setup.py (path:/tmp/pip-wntCHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:40:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:40:45 INFO - Downloading blessings-1.6.tar.gz 14:40:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:40:45 INFO - Installing collected packages: blessings 14:40:45 INFO - Running setup.py install for blessings 14:40:45 INFO - Successfully installed blessings 14:40:45 INFO - Cleaning up... 14:40:46 INFO - Return code: 0 14:40:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:40:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:40:46 INFO - Reading from file tmpfile_stdout 14:40:46 INFO - Current package versions: 14:40:46 INFO - argparse == 1.2.1 14:40:46 INFO - blessings == 1.6 14:40:46 INFO - blobuploader == 1.2.4 14:40:46 INFO - docopt == 0.6.1 14:40:46 INFO - functools32 == 3.2.3-2 14:40:46 INFO - jsonschema == 2.5.1 14:40:46 INFO - manifestparser == 1.1 14:40:46 INFO - mozInstall == 1.12 14:40:46 INFO - mozcrash == 1.0 14:40:46 INFO - mozdebug == 0.1 14:40:46 INFO - mozdevice == 0.49 14:40:46 INFO - mozfile == 1.2 14:40:46 INFO - mozhttpd == 0.7 14:40:46 INFO - mozinfo == 0.9 14:40:46 INFO - mozleak == 0.1 14:40:46 INFO - mozlog == 3.4 14:40:46 INFO - moznetwork == 0.27 14:40:46 INFO - mozprocess == 0.25 14:40:46 INFO - mozprofile == 0.28 14:40:46 INFO - mozrunner == 6.13 14:40:46 INFO - mozscreenshot == 0.1 14:40:46 INFO - mozsystemmonitor == 0.3 14:40:46 INFO - moztest == 0.8 14:40:46 INFO - mozversion == 1.4 14:40:46 INFO - psutil == 3.1.1 14:40:46 INFO - requests == 1.2.3 14:40:46 INFO - wsgiref == 0.1.2 14:40:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:40:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:40:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:46 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 0x14a51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1745ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, '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': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:40:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:40:46 INFO - Using env: {'DISPLAY': ':0', 14:40:46 INFO - 'HOME': '/home/cltbld', 14:40:46 INFO - 'LANG': 'en_US.UTF-8', 14:40:46 INFO - 'LANGUAGE': 'en_US:en', 14:40:46 INFO - 'LOGNAME': 'cltbld', 14:40:46 INFO - 'MAIL': '/var/mail/cltbld', 14:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:40:46 INFO - 'MOZ_NO_REMOTE': '1', 14:40:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:40:46 INFO - 'NO_EM_RESTART': '1', 14:40:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:40:46 INFO - 'PWD': '/builds/slave/test', 14:40:46 INFO - 'SHELL': '/bin/bash', 14:40:46 INFO - 'SHLVL': '1', 14:40:46 INFO - 'TERM': 'linux', 14:40:46 INFO - 'TMOUT': '86400', 14:40:46 INFO - 'USER': 'cltbld', 14:40:46 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:40:46 INFO - '_': '/tools/buildbot/bin/python'} 14:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:40:46 INFO - Cleaning up... 14:40:46 INFO - Return code: 0 14:40:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:40:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:40:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:40:46 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 0x14a51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1745ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, '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': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:40:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:40:46 INFO - Using env: {'DISPLAY': ':0', 14:40:46 INFO - 'HOME': '/home/cltbld', 14:40:46 INFO - 'LANG': 'en_US.UTF-8', 14:40:46 INFO - 'LANGUAGE': 'en_US:en', 14:40:46 INFO - 'LOGNAME': 'cltbld', 14:40:46 INFO - 'MAIL': '/var/mail/cltbld', 14:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:40:46 INFO - 'MOZ_NO_REMOTE': '1', 14:40:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:40:46 INFO - 'NO_EM_RESTART': '1', 14:40:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:40:46 INFO - 'PWD': '/builds/slave/test', 14:40:46 INFO - 'SHELL': '/bin/bash', 14:40:46 INFO - 'SHLVL': '1', 14:40:46 INFO - 'TERM': 'linux', 14:40:46 INFO - 'TMOUT': '86400', 14:40:46 INFO - 'USER': 'cltbld', 14:40:46 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:40:46 INFO - '_': '/tools/buildbot/bin/python'} 14:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:40:46 INFO - Cleaning up... 14:40:46 INFO - Return code: 0 14:40:46 INFO - Running post-action listener: _resource_record_post_action 14:40:46 INFO - Running post-action listener: _start_resource_monitoring 14:40:46 INFO - Starting resource monitoring. 14:40:46 INFO - [mozharness: 2017-02-17 22:40:46.755084Z] Finished create-virtualenv step (success) 14:40:46 INFO - [mozharness: 2017-02-17 22:40:46.755543Z] Running install step. 14:40:46 INFO - Running pre-action listener: _resource_record_pre_action 14:40:46 INFO - Running main action method: install 14:40:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:40:46 INFO - Reading from file tmpfile_stdout 14:40:46 INFO - Detecting whether we're running mozinstall >=1.0... 14:40:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:40:47 INFO - Reading from file tmpfile_stdout 14:40:47 INFO - Output received: 14:40:47 INFO - Usage: mozinstall [options] installer 14:40:47 INFO - Options: 14:40:47 INFO - -h, --help show this help message and exit 14:40:47 INFO - -d DEST, --destination=DEST 14:40:47 INFO - Directory to install application into. [default: 14:40:47 INFO - "/builds/slave/test"] 14:40:47 INFO - --app=APP Application being installed. [default: firefox] 14:40:47 INFO - mkdir: /builds/slave/test/build/application 14:40:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:41:03 INFO - Reading from file tmpfile_stdout 14:41:03 INFO - Output received: 14:41:03 INFO - /builds/slave/test/build/application/firefox/firefox 14:41:03 INFO - Running post-action listener: _resource_record_post_action 14:41:03 INFO - [mozharness: 2017-02-17 22:41:03.641160Z] Finished install step (success) 14:41:03 INFO - [mozharness: 2017-02-17 22:41:03.641438Z] Running run-tests step. 14:41:03 INFO - Running pre-action listener: _resource_record_pre_action 14:41:03 INFO - Running main action method: run_tests 14:41:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:41:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:41:03 INFO - Python 2.7.3 14:41:03 INFO - Return code: 0 14:41:03 INFO - grabbing minidump binary from tooltool 14:41:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:41:03 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 0x17cf540>, '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 0x1743f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1744400>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:41:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:41:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:41:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:41:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:41:03 INFO - Return code: 0 14:41:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:41:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:41:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:41:03 INFO - ENV: RUST_BACKTRACE is now 1 14:41:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:41:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:41:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:41:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-001 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:41:03 INFO - Using env: {'DISPLAY': ':0', 14:41:03 INFO - 'HOME': '/home/cltbld', 14:41:03 INFO - 'LANG': 'en_US.UTF-8', 14:41:03 INFO - 'LANGUAGE': 'en_US:en', 14:41:03 INFO - 'LOGNAME': 'cltbld', 14:41:03 INFO - 'MAIL': '/var/mail/cltbld', 14:41:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:41:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:03 INFO - 'MOZ_NO_REMOTE': '1', 14:41:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:41:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:03 INFO - 'NO_EM_RESTART': '1', 14:41:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:03 INFO - 'PWD': '/builds/slave/test', 14:41:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:41:03 INFO - 'RUST_BACKTRACE': '1', 14:41:03 INFO - 'SHELL': '/bin/bash', 14:41:03 INFO - 'SHLVL': '1', 14:41:03 INFO - 'TERM': 'linux', 14:41:03 INFO - 'TMOUT': '86400', 14:41:03 INFO - 'USER': 'cltbld', 14:41:03 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368', 14:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:03 INFO - '_': '/tools/buildbot/bin/python'} 14:41:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:41:03 INFO - mozversion application_buildid: 20170217214422 14:41:03 INFO - mozversion application_changeset: d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 14:41:03 INFO - mozversion application_display_name: Nightly 14:41:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:41:03 INFO - mozversion application_name: Firefox 14:41:03 INFO - mozversion application_remotingname: firefox 14:41:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:41:03 INFO - mozversion application_vendor: Mozilla 14:41:03 INFO - mozversion application_version: 54.0a1 14:41:03 INFO - mozversion platform_buildid: 20170217214422 14:41:03 INFO - mozversion platform_changeset: d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 14:41:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:41:03 INFO - mozversion platform_version: 54.0a1 14:41:03 INFO - using testdate: 1487371263 14:41:03 INFO - actual date: 1487371263 14:41:03 INFO - starting webserver on 'localhost:39454' 14:41:03 INFO - SUITE-START | Running 2 tests 14:41:03 INFO - TEST-START | dromaeo_css 14:41:03 INFO - operating with platform_type : linux_ 14:41:03 INFO - Initialising browser for dromaeo_css test... 14:41:03 INFO - TEST-INFO | started process 4424 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOqzHlS/profile http://localhost:39454/getInfo.html) 14:41:06 INFO - PROCESS | 4424 | Iterating over profile /tmp/tmpOqzHlS/profile 14:41:07 INFO - PROCESS | 4424 | __metrics Screen width/height:1600/1200 14:41:07 INFO - PROCESS | 4424 | colorDepth:24 14:41:07 INFO - PROCESS | 4424 | Browser inner width/height: 1024/697 14:41:07 INFO - PROCESS | 4424 | __metrics 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/safebrowsing = Fri Feb 17 2017 14:41:07 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/gmp = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/user.js = Fri Feb 17 2017 14:41:03 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/key3.db = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/cache2 = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/cert8.db = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/prefs.js = Fri Feb 17 2017 14:40:22 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/extensions.ini = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/localstore.rdf = Fri Feb 17 2017 14:40:22 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/content-prefs.sqlite = Fri Feb 17 2017 14:41:05 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/cookies.sqlite = Fri Feb 17 2017 14:41:07 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/addons.json = Fri Feb 17 2017 14:41:06 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/mimeTypes.rdf = Fri Feb 17 2017 14:41:07 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/crashes = Fri Feb 17 2017 14:41:03 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/directoryLinks.json = Fri Feb 17 2017 14:41:06 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/extensions.json = Fri Feb 17 2017 14:41:06 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/frequencyCap.json = Fri Feb 17 2017 14:41:06 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/.parentlock = Fri Feb 17 2017 14:41:03 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/places.sqlite = Fri Feb 17 2017 14:41:07 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/permissions.sqlite = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | Stat failure 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/compatibility.ini = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/places.sqlite-shm = Fri Feb 17 2017 14:41:05 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/thumbnails = Fri Feb 17 2017 14:41:06 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/startupCache = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/extensions = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/places.sqlite-wal = Fri Feb 17 2017 14:41:07 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/blocklist.xml = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/sessionCheckpoints.json = Fri Feb 17 2017 14:41:07 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | Stat failure 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/minidumps = Fri Feb 17 2017 14:41:03 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/bookmarkbackups = Fri Feb 17 2017 14:41:06 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/webappsstore.sqlite = Fri Feb 17 2017 14:41:07 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/pluginreg.dat = Fri Feb 17 2017 14:41:06 GMT-0800 (PST) 14:41:07 INFO - PROCESS | 4424 | No birth date. Using mtime. 14:41:07 INFO - PROCESS | 4424 | Using date: /tmp/tmpOqzHlS/profile/secmod.db = Fri Feb 17 2017 14:41:04 GMT-0800 (PST) 14:41:08 INFO - PROCESS | 4424 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:41:08 INFO - PROCESS | 4424 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:41:09 INFO - TEST-INFO | 4424: exit 0 14:41:09 INFO - Browser initialized. 14:41:09 INFO - Running cycle 1/1 for dromaeo_css test... 14:41:09 INFO - TEST-INFO | started process 4517 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOqzHlS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:41:21 INFO - PROCESS | 4517 | RSS: Main: 142278656 14:41:21 INFO - PROCESS | 4517 | 14:44:23 INFO - PROCESS | 4517 | Cycle 1(1): loaded http://localhost:39454/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39454/tests/dromaeo/cssquery-ext.html) 14:44:25 INFO - PROCESS | 4517 | RSS: Main: 278384640 14:44:25 INFO - PROCESS | 4517 | 14:46:11 INFO - PROCESS | 4517 | ************************* 14:46:11 INFO - PROCESS | 4517 | A coding exception was thrown and uncaught in a Task. 14:46:11 INFO - PROCESS | 4517 | 14:46:11 ERROR - PROCESS | 4517 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:46:11 INFO - PROCESS | 4517 | Full stack: 14:46:11 INFO - PROCESS | 4517 | ************************* 14:47:27 INFO - PROCESS | 4517 | Cycle 1(1): loaded http://localhost:39454/tests/dromaeo/cssquery-ext.html (next: http://localhost:39454/tests/dromaeo/cssquery-jquery.html) 14:47:28 INFO - PROCESS | 4517 | RSS: Main: 248733696 14:47:28 INFO - PROCESS | 4517 | 14:50:30 INFO - PROCESS | 4517 | Cycle 1(1): loaded http://localhost:39454/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39454/tests/dromaeo/cssquery-mootools.html) 14:50:30 INFO - PROCESS | 4517 | RSS: Main: 180797440 14:50:30 INFO - PROCESS | 4517 | 14:53:32 INFO - PROCESS | 4517 | Cycle 1(1): loaded http://localhost:39454/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39454/tests/dromaeo/cssquery-prototype.html) 14:53:33 INFO - PROCESS | 4517 | RSS: Main: 178991104 14:53:33 INFO - PROCESS | 4517 | 14:56:36 INFO - PROCESS | 4517 | Cycle 1(1): loaded http://localhost:39454/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39454/tests/dromaeo/cssquery-yui.html) 14:56:36 INFO - PROCESS | 4517 | RSS: Main: 177819648 14:56:36 INFO - PROCESS | 4517 | 14:59:39 INFO - PROCESS | 4517 | Cycle 1(1): loaded http://localhost:39454/tests/dromaeo/cssquery-yui.html (next: http://localhost:39454/tests/dromaeo/cssquery-dojo.html) 14:59:40 INFO - PROCESS | 4517 | RSS: Main: 279531520 14:59:40 INFO - PROCESS | 4517 | 14:59:40 INFO - PROCESS | 4517 | __start_tp_report 14:59:40 INFO - PROCESS | 4517 | _x_x_mozilla_page_load 14:59:40 INFO - PROCESS | 4517 | _x_x_mozilla_page_load_details 14:59:40 INFO - PROCESS | 4517 | |i|pagename|runs| 14:59:40 INFO - PROCESS | 4517 | |0;dojo.html;3135.905922822315;3141.7486601071914;3148.9212769680753;3151.43274210642;3209.181658677037;5106.821261255856;5203.9219411708855;5209.270702101709;5251.317328747262;5265;5180.1970694542315;5189.792408303672;5233.162693968966;5267.973660131704;5297.12597421425;4604.378408914796;4616.099860527199;4735.076660051292;4799.760011999386;4838.540338667838;4821.54195351441;4828.782704778276;4865.6594038417325;4876.000419913909;4896.902061958759;4618.307253911923;4625.537446255381;4645.349651048822;4693.859184224479;4844.975775121137;4659.161350956826;4687.624990000826;4702.412198475191;4704.435467743859;4711.151992641323;4689.507601701806;4719.457262414832;4750.45369782476;4768.117898188846;4769.451513075971;5231.764570594332;5238.240455134022;5253.658512196695;5277.9472205278225;5277.973610131963;5648.858778530528;5653.886922261573;5654.180143879114;5657.9434205658235;5698.487136157724;5499.50504454601;5559.8888022239325;5566.944330556724;5567.359753628303;5644;5189.766460509286;5252.763625636855;5262.500062494095;5263.499967503081;5263.789448422059;5725.141228815711;5761;5764.827055188351;5826.621269617462;5827.650340979555;5448.073827449343;5454.263674403984;5467.808626698046;5534.7509362078;5535.806246781343;5626.718664066781;5629.605927585111;5633.295838020247;5637.041702910515;5639.125935480007;5577.135543990688;5633.492985631313;5635;5692.174634677948;5709.286339207599;5661.235733175969;5670.234518339971;5671.6597004179885;5676.262085928885;5717.371089180187;4272.957270427318;4305.935410968838;4310.849120280838;4357.215701173819;4413.249747542925;3881.7670939743707;3915.7454765440157;3937.665298449607;3942.152437225953;3942.3692209246383;5258.868528286747;5259.710715910662;5260.13207820713;5262;5266.262723218752;5566.777328906879;5642.943570564242;5654.886902261932;5656.830295091154;5681.801136960186;5296.735163241822;5301.0723123453245;5332.813351532755;5333.893322133536;5347.545458636059;4804.5195480451675;4810.374651295309;4823.131836269435;4825.227963525819;4867.902641947141;5665.915011274917;5699.487046165865;5805.303363596311;5819.039858423313;5820.82537523875;5588.720563971867;5597.3003374578175;5600.27196464457;5600.663960162403;5602.803901863415;5665.951798917214;5749.108888122348;5776.393478684678;5815.360310365941;5815.76736930518;5578.135389014709;5619.8876022480135;5629.4652008059165;5631.183478395692;5631.380548139783;5720.856978575569;5726.627769194989;5729.28383952006;5729.541636668973;5731.684757338387;5388.919166212589;5431.918521222264;5573.5819813513335;5576.498115169492;5582.385937546947;5451.182322651475;5485.451454854483;5499.1476321170285;5500.4499550044675;5503.862403439946;5479.862853487223;5492.5605951524585;5538.280023596906;5560.944390556205;5584.553235741212;5564.4991950724625;5568.832935011957;5570.944290557205;5576.721163941867;5584.664920104807;5274.393444753902;5319.601029922694;5320.521153096085;5395.568354531707;5465.808696695596 14:59:40 INFO - PROCESS | 4517 | |1;ext.html;571.5653563001646;574.3653263144228;586.4428792646986;586.6919867069788;594.2720167794453;31977;32476.512852307234;32520.1869953251;43512.56487435125;44845.77577112118;44693.32960005602;45217.095658086815;45249.321260181125;45311.77344113283;45351;9081.318901082428;9278.675246366378;9281.535923203846;9281.907180928205;9288.256939444862;7438.144613369478;7629.8474030519355;9130.954345228265;10728;10980.890191098066;10468.267221294513;10523;10526.684199474028;10528.105111065568;10531.526081326321;10974.122070234402;11026.117910567176;11034.448277586089;11050.668479945614;11050.889491105067;10718.410487423189;10748.140148788156;10748.73128171794;10757.139428845674;10763.515641796137;42329.78835105805;42674.57325426737;42689;42850.14299713988;42891.142177156595;3743.794091325003;3746.587875333711;3771.9811400943095;3808.923821523582;3819.388897776342;31796.682033179604;31847.840760796047;31936.20159496008;32349.676503234903;32350.35299294001;3840.9615903841163;3952.150287688161;3970.7419017763755;4081.2245673322273;4134.400511925784;292591;293592;294005;294105;294162;55431.722841385534;55471.722641386536;55792.442075579536;55792.999999999185;55811.116832217325;59405.40594594085;59566;59638;59642.70178649079;59658;14936.626584335261;15118.546443606709;15151.696966060617;15165.545033649008;15199.468018619295;11362.54549817998;11365.829512557319;11377.544898204145;11431.59989400367;11450.19848610606;13203.40584673692;13762.518311859038;16702.349980807903;20799.480012999495;20872.165113395295;7140;7469.58917259556;7521.623918804038;7626.682393158993;7812.726554570563;810.1857633078719;846.1073567386344;847.737201467547;853.7140058080613;854.2567965869733;29620;30759;31027.379452410827;31162.68837311598;31181.68818311833;1006.2603986070233;1082.9025387714985;1091.770728147082;1104.823228283487;1109.2789686703707;15658.530244092695;16074.35702571884;16183.514494564946;16246.431374901822;16509.257083431035;481229;489157;489910;490024;490425;59562.10656840151;60232.39767602355;60253.69873150694;60714.696426518465;60813.69593152006;1346.4277681985313;1375.6354566040006;1380.3098450774814;1385.49429458247;1394.9721005580034;29236;30513.23716907095;30524;30541.847290763402;31580.368392632477;51211.487885122166;51800.222996654324;51806.74096629493;51897.48102519078;51973.22040169325;302516;302571.89560453716;302674;303079;303643;31995.48049714483;40172;41421;41453;41742;33918.491222632176;44146.77926610346;45214;46612.54199328091;48948.265776012675;35463.468047978786;37574.30332115382;38261.23477530378;39724;40742.1851562973;38395.616043840324;39207.6079239204;39341.803290984506;40844.59155408408;41022.79488602538;39123;39733.40399893946;39890.40164397479;40021.39967900542;40077.1984560313;32178.19554511156;34072.31855362929;34307.65692343045;34650.65349346474;34790.13024674403;26397.20808375752;26490.602640960788;26507;26995.730042700106;27150.189667494702 14:59:40 INFO - PROCESS | 4517 | |2;jquery.html;378.91474418255876;379.3532027892444;385.7376983651117;390.23319177815586;401.8252060353746;18218.180181891814;20133.697994530095;20925.37223883282;20987.265445709403;21435.14259429623;20331;20657.17371305146;20741.688874666892;20766.169353225854;20866.374008779723;5814.4766970972605;6024.909626355608;7403.296686814744;7649.349805266559;7799.415043871704;7226.56640601563;7579.090509138911;7742.574158421313;7812.414068944823;7841.6079196040255;7753.635141281935;7756.0692716874055;7767.922320776805;7775.455718099735;7782.805429864242;7572.583507907063;7828.804279892991;7883.792538089344;7908.881366779503;7911.2088791121005;7058.505904586681;7373.446991475661;7434.888476672854;7445.8883116753295;7665.386769058462;27315.590266146024;27757.583636245472;27812;27835.860820695765;28031.579526307123;2761.1164427383246;2802.7477527022666;2827.604135421043;2855.1434569629237;2879.9424011519654;15724.449644262397;15974.281157347947;16044.598885027852;16253.431129910396;17195.31218751261;1984.2162345873298;2009.779193990634;2010.155734591475;2025.1494372363495;2028.8328746435675;98907.50546247223;104134.47932760364;105211.47394263056;105981.47009264982;106040;18393.54016149607;18988;19170;19210.423687289403;19333.523269704525;35227;35267.4709879352;35321.29357412889;35349.11627209289;35526.64473355285;9152.81694366109;9589.664361747306;9614.71155865325;9631.855522167172;9912.801743965225;7726.150123486412;7764.883526747103;7992.400569957276;8002;8035.196480351918;9248.398854074463;9719.201947639709;9899.505024748878;10054.19566434698;10136.493175341204;3693.353663108945;3704.5554533456157;3763.7741735495956;3823.9426408603895;4026.516817981861;843.1568431568431;861.1130535548396;876.706303388372;883.7172104926491;902.4540153207346;19464.416067517996;20228.190872365234;20301.086451109735;20317.390478285673;20451.897740511202;861.1904809479195;874.6501399440275;874.903760586335;876.2639382918379;878.3719640457103;10431;11024.283421577571;11099.611513596985;11170.776584468265;11251.09991200702;610350;613507;622229;630291;651176;103148.48425757824;105583;109018.45490772495;110304;111579;1035.53400969564;1057.1278695076592;1101.823708206683;1101.9173561982884;1108.4924502676192;17226.655466890843;17460;17922.283108675507;17998;18066.458006259832;38444.61555384522;39852.40221396739;40533.25868644752;41983;43962.12075758531;60627.393726063936;60792.696036519534;61042;62738.686306568175;63373;8886.777830554029;8945.23965462917;9065.954670226607;9103.954480227556;9252.761533463234;8215;13619.387127579084;13693.109947853358;14218;14233.430662773373;11599.246049006788;12266.570670026505;12356.505739770308;12408.131430799758;12746.490140394651;11662;12107.394630268274;12478.5008599655;12525.185862918881;12625.558105466265;12096.879031209575;12217.0226381891;12656.43046062911;12774.936125319315;13100.148490348098;8447.113053129333;8616.439931404439;8629.87055194185;8830.792301924519;9358;10392.37645741258;12378.81431778506;12398.81401778956;12486.937565312115;12594.992400608113 14:59:40 INFO - PROCESS | 4517 | |3;mootools.html;1234.9135560510767;1404.9297535123249;1407.472197925778;1414.5402744108164;1419.1414194412382;6949.930500694992;8116.472429292099;8348.582570871462;8350.498970061786;8542.957285213566;8373.623186956587;8413;8437.873431898526;8440.155984401557;8440.409171358007;1926.3257859749046;2249.7862703043224;2277.146070223666;2277.4761804784825;2279.2136712834044;1994.700794880765;2012.8490363222743;2014.4460273424852;2017.072146812468;2170.869747815136;2232.274510784002;2247;2248.2468373094953;2249.921252756154;2254.0195015168456;2079.189116244666;2102.7581828089737;2105.52625659227;2112.6936594193908;2117.9258725944674;2144.785521447858;2210.2264207527396;2230.6877037214804;2231.7433495147943;2235.675827005075;8349.165083491664;8385.748427547183;8405.873911891327;8409.873851892227;8429.98840139181;5106.131957567222;5251.396089449724;5255.474452554752;5259.894802103937;5268.236105764681;3199.216192032967;3258.0714496368423;3299.538064670948;3306.338732253535;3351.614564325085;1287.0475847872508;1295.1581472043154;1316.433933408634;1335.1855368225374;1374.9450021999107;11670.591529296518;11772.058235341237;11809.40952952349;11816.291022538675;11830.408479576074;4331.393604895317;4366.410534577855;4371.540988196257;4405.185040767468;4486.573775491323;9173.541322933826;9177.311701622404;9178.586963586655;9194.310426718022;9194.402363846328;5189.818356357509;5191.454897235812;5194.428612852583;5197.506236907488;5202.583793296603;4627.120847039052;4767.61859051275;4813.687110337887;4832.178529649968;4891.119598472309;3241.918952026171;3354.3459025490283;3574.463830425405;3600.765950213228;3619;1080.7568297133082;1090.907272881814;1100.6973082402403;1101.3557069114497;1112.5605385872698;987.8666380038603;988.6342053440274;1006.1849901579744;1008.7780688248573;1036.549101141006;5163.690178589297;5484.040292948718;5858.414158584107;5944.5838791284195;5970.373110823388;987.8024395121005;1035.0839507036328;1050.4642632257603;1061.9681409557725;1073.5024640626282;3877.515310586177;4045.9190816183514;4148.896277593024;4163.958360416417;4209.97895010523;9953.950230248802;10271.126954208827;10277.17782577392;10283.125934295518;10289.125424339016;10164.085232329126;10212.540435680412;10212.948935255276;10218.591256349811;10273.486325683685;7581.696732130763;7859.724909628136;7988.720394786154;8064.677412903536;8108.797280068045;5890.52875769946;5912;5974.312954010345;6060.848478787889;6132.601380910227;9174.08259174104;9193.172614464731;9216.2626989842;9242.306826988137;9336.906630933603;9096.090390960851;9153.908460915305;9250.4912229828;9427.952860235655;9429;7524.05949256343;7644.464887457824;7799.844003120019;7817.140114587504;7837.255460731221;7802.960985195038;7809.96095019544;7852.646630901738;7877.803054923673;7973.322267607318;7131.393831524379;7153.2131465537705;7165.964170179116;7224;7337.119545654556;6844.691988860613;7021.754238601625;7034.366906978396;7308.196098429275;7368.410527157922;6315.242170939517;6424.51816113784;6477.514186435942;6479.902801457888;6481.35186481367;5260.89478210426;5279.841604751864;5279.920801188062;5342.732863356739;5419.756110975094;5379;5408.405075441777;5436.836894893159;5457.471907865804;5576.665400076008 14:59:40 INFO - PROCESS | 4517 | |4;prototype.html;372.44319741985737;373.8691457989704;375.539963544658;383.58572741439247;383.64896120050156;5100.642954993143;5155.845324640262;5182.144946083897;5185.999999999995;5191.844244672657;5161.483851614837;5187.740612969355;5210.8697282567955;5253.947460525394;5261.500157485042;3991.8602848900296;4115.773632450214;4126.628603425691;4140.979295103528;4151.460310159675;4104.8563300284495;4110.6300432961025;4113.321301985184;4118.38224266361;4125.566815484376;4114.9630932688215;4125.731827431222;4129.834806607747;4132.8966775830695;4138.13099249158;3945.506811648544;4078.938815917764;4156.5635608261155;4156.625903668684;4162.958370416287;3659.5242618459697;3706.2031663192274;3706.592274849764;3712.7401081924277;3720.553533575961;5015.172496538067;5096.464871188546;5108.872278193075;5115.437301896788;5117.616178786606;4560.065186636732;4807.831725889577;4809.278608208797;4817.807287708522;4819.759012049419;5501.697406642633;5512.283403157564;5520.171974203861;5523.337199536041;5535.861603459905;4126.741343890112;4132.690048246393;4142.420061191436;4143.047099167178;4144.067584793397;4976.87557811054;5389.946100539023;5719.3136823581435;5753.769849206027;5764.607687809554;4116.279651061052;4219.620234178878;4223.761406454584;4224.176285624335;4236.131593023423;4270.679699022586;4272.632757517565;4276.743395396224;4286.185624731291;4287.228298906227;4767.284907263869;4772.308015337826;4779.498152693986;4785.736784476887;4794.664373493892;5416.729163541807;5432.809851655173;5468.179773034077;5468.589855760833;5506.182959623315;1406.8522805105317;1409.365785396555;1418.7729963206043;1419.552840855148;1425.4583258361756;973.6981535723949;1019.169376957777;1032.7108409645311;1041.9374837509774;1051.574112484445;5158.38099428071;5161.071007218661;5165.199394093921;5170.1986192140275;5177.611679124006;5496.674511920311;5508.944910550923;5513.58648101386;5515.227868098469;5525.557955363562;4900.338454308623;5024.015513872;5087.287779710843;5092.567131793765;5102.33669622954;2856.2573730829718;2868.6987866274158;2878.8992385266833;2887.1771545109964;2893.2766808297924;5616.6068375213345;5637.267155269788;5639.43605639441;5639.746211420496;5646.6894320811925;5230.947690523046;5466.808661696821;5506.614536982453;5538.889222215614;5574.052411090124;5616.466435688603;5656.830295091154;5700.543956483471;5764.510016648632;5785;5580.748866300945;5587.720613969366;5589.608727389043;5599.832005039855;5607.607467477237;5364;5364.30264065669;5485.232067510631;5504.339479262514;5542.8337149885565;5711.914321285267;5725.19847221381;5742.530711793968;5755.453231942959;5789.189513468202;4158.9584104158575;4185.476815398075;4198.244316023023;4210.30529962565;4215.3044747616295;5284.709340986283;5549.3074612242035;5597.720113994365;5634.408387119293;5654.915176272277;5461.262729531462;5486.780528778964;5508.614396992172;5518.42056584069;5546.722663866871;5238.371395432572;5413.296271484682;5453.263809385682;5479.369872464717;5506.944930550643;5486.653141583551;5493.708978390155;5495.697736624524;5505.834824955257;5509.338879334506;5566.109422492382;5575.303087114111;5578.843761397785;5599.496045355938;5614.438556144353;5022.698638081727;5344;5361.651492652965;5448.237246785532;5468.726563671721 14:59:40 INFO - PROCESS | 4517 | |5;yui.html;730.6127752291284;744.0476190476193;772.8454309138174;819.1644522586962;821.3224090125648;15066.623334416632;15185.316660750264;15861.20693965303;15882.126483043428;16141.596460088489;16081.356745730185;16663.50009499714;16681.416150434736;16845.578860528494;16900;771.8031901865016;784.7920301120193;803.5901690138043;805.9838803223932;806.4717609965483;796.9997652945545;815.9347252219808;816.481534225768;816.8978877640295;816.9264766171043;851.0340763233743;859.3340161374922;861.2765277167179;861.6208868098048;861.7716305179133;849.9970035358275;852.2244757270915;853.2747164909808;853.3727710133047;853.4324674091722;624.1199908129561;624.9906251406233;625.0405627361978;625.5433533520555;626.4737620398841;15850.682986340209;15920.044797312199;16485.258163382678;16538.83461165385;16691.165441727866;15369.154696491689;15620.531384058497;15844.28700708471;15993.52019439407;16111.27499262536;11610.24533405326;11639.883601163965;11699.88300117005;11759.882401175964;12577.11960162789;3640.199156185635;3721.60923103073;3736.4955730976512;3818.217265460574;3844.942325865114;77233;78146.21853781446;78753.60623196905;79603.60198198972;79879.6006019972;11548.480318385693;11555.083104006597;11619.128565357632;11871.228370155912;11964;23793.405164871016;23838.880805596207;24044.15845445436;24223.456537063354;24241.878790606286;7620.847583048308;7622.733204337822;7652.234776522303;7653.081630204411;7998.760037198894;4206.28493156158;4230.175115852441;4232.746035237896;4306.978465107654;4393.384926110347;3491.2319289756215;3527.470879368064;3533.3463309287863;3561.3767590671528;3578.3380074686265;556.9303837020373;562.7748900439857;567.324883388766;574.7930744931821;632.4718859752066;712.7291629180982;718.4109030594863;740.0188962044326;748.4274529984566;755.2560727683227;10420;10573.682789516326;10674.519646615921;10759.946200268949;10841.620543280947;727.1238158019573;763.5075376382202;765.3609236287651;774.3882332957013;779.3492433817712;4566.9057630472425;4929.035970652002;4955.950440495549;4957.429895561984;4968.875778105504;70882.2911770886;71689;72137.63931180311;73357.6332118336;74548.25451745413;66469;66477;66921;68125.93433295513;69429.42526229164;59769.40230597725;60060.69969650124;60610.39389606047;61066;61486.69256653778;9551.665691700757;9588.185004274435;9614.9038509614;9618.951905240428;9638.277129215197;39494;40578.391324129574;40826.387604185365;41508.169836602494;41951.7902410486;43821.561784381745;45827.08345833131;47273.527264726916;47379;50451.49548504615;12431.502739890304;12434.440450179583;12683.556075537312;13117.737645247233;13993.020488565702;14815.629609259855;15171.620709482351;15225.619359515658;15422.46021389246;16004.519864404087;13572.1856688599;13863.168209907439;14147.64630884236;14760;14837.703245935238;13749.312534373441;14037.368318425491;14248.145111293356;14266.785998210244;14443.711125777216;13911.373988170355;14074.929625351808;14077.859221407654;14171.787423188867;14636.195009274166;10306.072453479223;10615.681529554126;10615.787684246427;10780.460976951277;10917.945410272898;9867.506624668595;10011.499425028574;10516.842247366143;11074.446277685922;11309.179418630261 14:59:40 INFO - PROCESS | 4517 | __end_tp_report 14:59:40 INFO - PROCESS | 4517 | __start_cc_report 14:59:40 INFO - PROCESS | 4517 | _x_x_mozilla_cycle_collect,1463 14:59:40 INFO - PROCESS | 4517 | __end_cc_report 14:59:40 INFO - PROCESS | 4517 | __startTimestamp1487372380071__endTimestamp 14:59:40 INFO - PROCESS | 4517 | 14:59:40 INFO - PROCESS | 4517 | ------- Summary: start ------- 14:59:40 INFO - PROCESS | 4517 | Number of tests: 6 14:59:40 INFO - PROCESS | 4517 | 14:59:40 INFO - PROCESS | 4517 | [#0] dojo.html Cycles:165 Average:5227.02 Median:5451.18 stddev:582.23 (10.7%) stddev-sans-first:560.43 14:59:40 INFO - PROCESS | 4517 | Values: 3135.9 3141.7 3148.9 3151.4 3209.2 5106.8 5203.9 5209.3 5251.3 5265.0 5180.2 5189.8 5233.2 5268.0 5297.1 4604.4 4616.1 4735.1 4799.8 4838.5 4821.5 4828.8 4865.7 4876.0 4896.9 4618.3 4625.5 4645.3 4693.9 4845.0 4659.2 4687.6 4702.4 4704.4 4711.2 4689.5 4719.5 4750.5 4768.1 4769.5 5231.8 5238.2 5253.7 5277.9 5278.0 5648.9 5653.9 5654.2 5657.9 5698.5 5499.5 5559.9 5566.9 5567.4 5644.0 5189.8 5252.8 5262.5 5263.5 5263.8 5725.1 5761.0 5764.8 5826.6 5827.7 5448.1 5454.3 5467.8 5534.8 5535.8 5626.7 5629.6 5633.3 5637.0 5639.1 5577.1 5633.5 5635.0 5692.2 5709.3 5661.2 5670.2 5671.7 5676.3 5717.4 4273.0 4305.9 4310.8 4357.2 4413.2 3881.8 3915.7 3937.7 3942.2 3942.4 5258.9 5259.7 5260.1 5262.0 5266.3 5566.8 5642.9 5654.9 5656.8 5681.8 5296.7 5301.1 5332.8 5333.9 5347.5 4804.5 4810.4 4823.1 4825.2 4867.9 5665.9 5699.5 5805.3 5819.0 5820.8 5588.7 5597.3 5600.3 5600.7 5602.8 5666.0 5749.1 5776.4 5815.4 5815.8 5578.1 5619.9 5629.5 5631.2 5631.4 5720.9 5726.6 5729.3 5729.5 5731.7 5388.9 5431.9 5573.6 5576.5 5582.4 5451.2 5485.5 5499.1 5500.4 5503.9 5479.9 5492.6 5538.3 5560.9 5584.6 5564.5 5568.8 5570.9 5576.7 5584.7 5274.4 5319.6 5320.5 5395.6 5465.8 14:59:40 INFO - PROCESS | 4517 | 14:59:40 INFO - PROCESS | 4517 | [#1] ext.html Cycles:180 Average:53376.37 Median:20673.14 stddev:98369.22 (475.8%) stddev-sans-first:98565.28 14:59:40 INFO - PROCESS | 4517 | Values: 571.6 574.4 586.4 586.7 594.3 31977.0 32476.5 32520.2 43512.6 44845.8 44693.3 45217.1 45249.3 45311.8 45351.0 9081.3 9278.7 9281.5 9281.9 9288.3 7438.1 7629.8 9131.0 10728.0 10980.9 10468.3 10523.0 10526.7 10528.1 10531.5 10974.1 11026.1 11034.4 11050.7 11050.9 10718.4 10748.1 10748.7 10757.1 10763.5 42329.8 42674.6 42689.0 42850.1 42891.1 3743.8 3746.6 3772.0 3808.9 3819.4 31796.7 31847.8 31936.2 32349.7 32350.4 3841.0 3952.2 3970.7 4081.2 4134.4 292591.0 293592.0 294005.0 294105.0 294162.0 55431.7 55471.7 55792.4 55793.0 55811.1 59405.4 59566.0 59638.0 59642.7 59658.0 14936.6 15118.5 15151.7 15165.5 15199.5 11362.5 11365.8 11377.5 11431.6 11450.2 13203.4 13762.5 16702.3 20799.5 20872.2 7140.0 7469.6 7521.6 7626.7 7812.7 810.2 846.1 847.7 853.7 854.3 29620.0 30759.0 31027.4 31162.7 31181.7 1006.3 1082.9 1091.8 1104.8 1109.3 15658.5 16074.4 16183.5 16246.4 16509.3 481229.0 489157.0 489910.0 490024.0 490425.0 59562.1 60232.4 60253.7 60714.7 60813.7 1346.4 1375.6 1380.3 1385.5 1395.0 29236.0 30513.2 30524.0 30541.8 31580.4 51211.5 51800.2 51806.7 51897.5 51973.2 302516.0 302571.9 302674.0 303079.0 303643.0 31995.5 40172.0 41421.0 41453.0 41742.0 33918.5 44146.8 45214.0 46612.5 48948.3 35463.5 37574.3 38261.2 39724.0 40742.2 38395.6 39207.6 39341.8 40844.6 41022.8 39123.0 39733.4 39890.4 40021.4 40077.2 32178.2 34072.3 34307.7 34650.7 34790.1 26397.2 26490.6 26507.0 26995.7 27150.2 14:59:40 INFO - PROCESS | 4517 | 14:59:40 INFO - PROCESS | 4517 | [#2] jquery.html Cycles:180 Average:35692.05 Median:35247.24 stddev:102948.87 (292.1%) stddev-sans-first:103203.52 14:59:40 INFO - PROCESS | 4517 | Values: 378.9 379.4 385.7 390.2 401.8 18218.2 20133.7 20925.4 20987.3 21435.1 20331.0 20657.2 20741.7 20766.2 20866.4 5814.5 6024.9 7403.3 7649.3 7799.4 7226.6 7579.1 7742.6 7812.4 7841.6 7753.6 7756.1 7767.9 7775.5 7782.8 7572.6 7828.8 7883.8 7908.9 7911.2 7058.5 7373.4 7434.9 7445.9 7665.4 27315.6 27757.6 27812.0 27835.9 28031.6 2761.1 2802.7 2827.6 2855.1 2879.9 15724.4 15974.3 16044.6 16253.4 17195.3 1984.2 2009.8 2010.2 2025.1 2028.8 98907.5 104134.5 105211.5 105981.5 106040.0 18393.5 18988.0 19170.0 19210.4 19333.5 35227.0 35267.5 35321.3 35349.1 35526.6 9152.8 9589.7 9614.7 9631.9 9912.8 7726.2 7764.9 7992.4 8002.0 8035.2 9248.4 9719.2 9899.5 10054.2 10136.5 3693.4 3704.6 3763.8 3823.9 4026.5 843.2 861.1 876.7 883.7 902.5 19464.4 20228.2 20301.1 20317.4 20451.9 861.2 874.7 874.9 876.3 878.4 10431.0 11024.3 11099.6 11170.8 11251.1 610350.0 613507.0 622229.0 630291.0 651176.0 103148.5 105583.0 109018.5 110304.0 111579.0 1035.5 1057.1 1101.8 1101.9 1108.5 17226.7 17460.0 17922.3 17998.0 18066.5 38444.6 39852.4 40533.3 41983.0 43962.1 60627.4 60792.7 61042.0 62738.7 63373.0 8886.8 8945.2 9066.0 9104.0 9252.8 8215.0 13619.4 13693.1 14218.0 14233.4 11599.2 12266.6 12356.5 12408.1 12746.5 11662.0 12107.4 12478.5 12525.2 12625.6 12096.9 12217.0 12656.4 12774.9 13100.1 8447.1 8616.4 8629.9 8830.8 9358.0 10392.4 12378.8 12398.8 12486.9 12595.0 14:59:40 INFO - PROCESS | 4517 | 14:59:40 INFO - PROCESS | 4517 | [#3] mootools.html Cycles:180 Average:5516.27 Median:5195.97 stddev:3061.16 (58.9%) stddev-sans-first:3052.83 14:59:40 INFO - PROCESS | 4517 | Values: 1234.9 1404.9 1407.5 1414.5 1419.1 6949.9 8116.5 8348.6 8350.5 8543.0 8373.6 8413.0 8437.9 8440.2 8440.4 1926.3 2249.8 2277.1 2277.5 2279.2 1994.7 2012.8 2014.4 2017.1 2170.9 2232.3 2247.0 2248.2 2249.9 2254.0 2079.2 2102.8 2105.5 2112.7 2117.9 2144.8 2210.2 2230.7 2231.7 2235.7 8349.2 8385.7 8405.9 8409.9 8430.0 5106.1 5251.4 5255.5 5259.9 5268.2 3199.2 3258.1 3299.5 3306.3 3351.6 1287.0 1295.2 1316.4 1335.2 1374.9 11670.6 11772.1 11809.4 11816.3 11830.4 4331.4 4366.4 4371.5 4405.2 4486.6 9173.5 9177.3 9178.6 9194.3 9194.4 5189.8 5191.5 5194.4 5197.5 5202.6 4627.1 4767.6 4813.7 4832.2 4891.1 3241.9 3354.3 3574.5 3600.8 3619.0 1080.8 1090.9 1100.7 1101.4 1112.6 987.9 988.6 1006.2 1008.8 1036.5 5163.7 5484.0 5858.4 5944.6 5970.4 987.8 1035.1 1050.5 1062.0 1073.5 3877.5 4045.9 4148.9 4164.0 4210.0 9954.0 10271.1 10277.2 10283.1 10289.1 10164.1 10212.5 10212.9 10218.6 10273.5 7581.7 7859.7 7988.7 8064.7 8108.8 5890.5 5912.0 5974.3 6060.8 6132.6 9174.1 9193.2 9216.3 9242.3 9336.9 9096.1 9153.9 9250.5 9428.0 9429.0 7524.1 7644.5 7799.8 7817.1 7837.3 7803.0 7810.0 7852.6 7877.8 7973.3 7131.4 7153.2 7166.0 7224.0 7337.1 6844.7 7021.8 7034.4 7308.2 7368.4 6315.2 6424.5 6477.5 6479.9 6481.4 5260.9 5279.8 5279.9 5342.7 5419.8 5379.0 5408.4 5436.8 5457.5 5576.7 14:59:40 INFO - PROCESS | 4517 | 14:59:40 INFO - PROCESS | 4517 | [#4] prototype.html Cycles:180 Average:4621.20 Median:5163.34 stddev:1314.29 (25.5%) stddev-sans-first:1278.71 14:59:40 INFO - PROCESS | 4517 | Values: 372.4 373.9 375.5 383.6 383.6 5100.6 5155.8 5182.1 5186.0 5191.8 5161.5 5187.7 5210.9 5253.9 5261.5 3991.9 4115.8 4126.6 4141.0 4151.5 4104.9 4110.6 4113.3 4118.4 4125.6 4115.0 4125.7 4129.8 4132.9 4138.1 3945.5 4078.9 4156.6 4156.6 4163.0 3659.5 3706.2 3706.6 3712.7 3720.6 5015.2 5096.5 5108.9 5115.4 5117.6 4560.1 4807.8 4809.3 4817.8 4819.8 5501.7 5512.3 5520.2 5523.3 5535.9 4126.7 4132.7 4142.4 4143.0 4144.1 4976.9 5389.9 5719.3 5753.8 5764.6 4116.3 4219.6 4223.8 4224.2 4236.1 4270.7 4272.6 4276.7 4286.2 4287.2 4767.3 4772.3 4779.5 4785.7 4794.7 5416.7 5432.8 5468.2 5468.6 5506.2 1406.9 1409.4 1418.8 1419.6 1425.5 973.7 1019.2 1032.7 1041.9 1051.6 5158.4 5161.1 5165.2 5170.2 5177.6 5496.7 5508.9 5513.6 5515.2 5525.6 4900.3 5024.0 5087.3 5092.6 5102.3 2856.3 2868.7 2878.9 2887.2 2893.3 5616.6 5637.3 5639.4 5639.7 5646.7 5230.9 5466.8 5506.6 5538.9 5574.1 5616.5 5656.8 5700.5 5764.5 5785.0 5580.7 5587.7 5589.6 5599.8 5607.6 5364.0 5364.3 5485.2 5504.3 5542.8 5711.9 5725.2 5742.5 5755.5 5789.2 4159.0 4185.5 4198.2 4210.3 4215.3 5284.7 5549.3 5597.7 5634.4 5654.9 5461.3 5486.8 5508.6 5518.4 5546.7 5238.4 5413.3 5453.3 5479.4 5506.9 5486.7 5493.7 5495.7 5505.8 5509.3 5566.1 5575.3 5578.8 5599.5 5614.4 5022.7 5344.0 5361.7 5448.2 5468.7 14:59:40 INFO - PROCESS | 4517 | 14:59:40 INFO - PROCESS | 4517 | [#5] yui.html Cycles:180 Average:17286.71 Median:4231.46 stddev:21319.96 (503.8%) stddev-sans-first:21343.52 14:59:40 INFO - PROCESS | 4517 | Values: 730.6 744.0 772.8 819.2 821.3 15066.6 15185.3 15861.2 15882.1 16141.6 16081.4 16663.5 16681.4 16845.6 16900.0 771.8 784.8 803.6 806.0 806.5 797.0 815.9 816.5 816.9 816.9 851.0 859.3 861.3 861.6 861.8 850.0 852.2 853.3 853.4 853.4 624.1 625.0 625.0 625.5 626.5 15850.7 15920.0 16485.3 16538.8 16691.2 15369.2 15620.5 15844.3 15993.5 16111.3 11610.2 11639.9 11699.9 11759.9 12577.1 3640.2 3721.6 3736.5 3818.2 3844.9 77233.0 78146.2 78753.6 79603.6 79879.6 11548.5 11555.1 11619.1 11871.2 11964.0 23793.4 23838.9 24044.2 24223.5 24241.9 7620.8 7622.7 7652.2 7653.1 7998.8 4206.3 4230.2 4232.7 4307.0 4393.4 3491.2 3527.5 3533.3 3561.4 3578.3 556.9 562.8 567.3 574.8 632.5 712.7 718.4 740.0 748.4 755.3 10420.0 10573.7 10674.5 10759.9 10841.6 727.1 763.5 765.4 774.4 779.3 4566.9 4929.0 4956.0 4957.4 4968.9 70882.3 71689.0 72137.6 73357.6 74548.3 66469.0 66477.0 66921.0 68125.9 69429.4 59769.4 60060.7 60610.4 61066.0 61486.7 9551.7 9588.2 9614.9 9619.0 9638.3 39494.0 40578.4 40826.4 41508.2 41951.8 43821.6 45827.1 47273.5 47379.0 50451.5 12431.5 12434.4 12683.6 13117.7 13993.0 14815.6 15171.6 15225.6 15422.5 16004.5 13572.2 13863.2 14147.6 14760.0 14837.7 13749.3 14037.4 14248.1 14266.8 14443.7 13911.4 14074.9 14077.9 14171.8 14636.2 10306.1 10615.7 10615.8 10780.5 10917.9 9867.5 10011.5 10516.8 11074.4 11309.2 14:59:40 INFO - PROCESS | 4517 | -------- Summary: end -------- 14:59:40 INFO - PROCESS | 4517 | 14:59:40 INFO - TEST-INFO | 4517: exit 0 14:59:40 INFO - TEST-OK | dromaeo_css | took 1117004ms 14:59:40 INFO - TEST-START | kraken 14:59:40 INFO - operating with platform_type : linux_ 14:59:40 INFO - Initialising browser for kraken test... 14:59:40 INFO - TEST-INFO | started process 4835 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsUpNXK/profile http://localhost:39454/getInfo.html) 14:59:43 INFO - PROCESS | 4835 | Iterating over profile /tmp/tmpsUpNXK/profile 14:59:44 INFO - PROCESS | 4835 | __metrics Screen width/height:1600/1200 14:59:44 INFO - PROCESS | 4835 | colorDepth:24 14:59:44 INFO - PROCESS | 4835 | Browser inner width/height: 1024/697 14:59:44 INFO - PROCESS | 4835 | __metrics 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/safebrowsing = Fri Feb 17 2017 14:59:44 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/gmp = Fri Feb 17 2017 14:59:41 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/times.json = Fri Feb 17 2017 14:59:44 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/user.js = Fri Feb 17 2017 14:59:40 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/key3.db = Fri Feb 17 2017 14:59:41 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/cache2 = Fri Feb 17 2017 14:59:41 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/cert8.db = Fri Feb 17 2017 14:59:41 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/prefs.js = Fri Feb 17 2017 14:40:22 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/extensions.ini = Fri Feb 17 2017 14:59:41 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/localstore.rdf = Fri Feb 17 2017 14:40:22 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/content-prefs.sqlite = Fri Feb 17 2017 14:59:43 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/cookies.sqlite = Fri Feb 17 2017 14:59:44 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/addons.json = Fri Feb 17 2017 14:59:43 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/mimeTypes.rdf = Fri Feb 17 2017 14:59:44 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/crashes = Fri Feb 17 2017 14:59:40 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/directoryLinks.json = Fri Feb 17 2017 14:59:43 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/extensions.json = Fri Feb 17 2017 14:59:43 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/frequencyCap.json = Fri Feb 17 2017 14:59:43 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/.parentlock = Fri Feb 17 2017 14:59:40 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/places.sqlite = Fri Feb 17 2017 14:59:44 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/permissions.sqlite = Fri Feb 17 2017 14:59:42 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | Stat failure 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/compatibility.ini = Fri Feb 17 2017 14:59:41 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/places.sqlite-shm = Fri Feb 17 2017 14:59:42 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/thumbnails = Fri Feb 17 2017 14:59:43 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/startupCache = Fri Feb 17 2017 14:59:41 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/extensions = Fri Feb 17 2017 14:59:41 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/places.sqlite-wal = Fri Feb 17 2017 14:59:44 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/blocklist.xml = Fri Feb 17 2017 14:59:41 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:44 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/sessionCheckpoints.json = Fri Feb 17 2017 14:59:44 GMT-0800 (PST) 14:59:44 INFO - PROCESS | 4835 | Stat failure 14:59:44 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:45 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/minidumps = Fri Feb 17 2017 14:59:40 GMT-0800 (PST) 14:59:45 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:45 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/bookmarkbackups = Fri Feb 17 2017 14:59:43 GMT-0800 (PST) 14:59:45 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:45 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/webappsstore.sqlite = Fri Feb 17 2017 14:59:44 GMT-0800 (PST) 14:59:45 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:45 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/pluginreg.dat = Fri Feb 17 2017 14:59:43 GMT-0800 (PST) 14:59:45 INFO - PROCESS | 4835 | No birth date. Using mtime. 14:59:45 INFO - PROCESS | 4835 | Using date: /tmp/tmpsUpNXK/profile/secmod.db = Fri Feb 17 2017 14:59:41 GMT-0800 (PST) 14:59:45 INFO - PROCESS | 4835 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:59:45 INFO - PROCESS | 4835 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:59:46 INFO - TEST-INFO | 4835: exit 0 14:59:46 INFO - Browser initialized. 14:59:46 INFO - Running cycle 1/1 for kraken test... 14:59:46 INFO - TEST-INFO | started process 4927 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsUpNXK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:59:58 INFO - PROCESS | 4927 | RSS: Main: 142757888 14:59:58 INFO - PROCESS | 4927 | 15:00:01 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39454/tests/kraken/driver.html?testName=audio-beat-detection) 15:00:02 INFO - PROCESS | 4927 | RSS: Main: 198365184 15:00:02 INFO - PROCESS | 4927 | 15:00:04 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39454/tests/kraken/driver.html?testName=audio-dft) 15:00:05 INFO - PROCESS | 4927 | RSS: Main: 176898048 15:00:05 INFO - PROCESS | 4927 | 15:00:08 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39454/tests/kraken/driver.html?testName=audio-fft) 15:00:08 INFO - PROCESS | 4927 | RSS: Main: 258408448 15:00:08 INFO - PROCESS | 4927 | 15:00:11 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39454/tests/kraken/driver.html?testName=audio-oscillator) 15:00:11 INFO - PROCESS | 4927 | RSS: Main: 170926080 15:00:11 INFO - PROCESS | 4927 | 15:00:13 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39454/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:00:14 INFO - PROCESS | 4927 | RSS: Main: 174305280 15:00:14 INFO - PROCESS | 4927 | 15:00:17 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39454/tests/kraken/driver.html?testName=imaging-darkroom) 15:00:17 INFO - PROCESS | 4927 | RSS: Main: 213876736 15:00:17 INFO - PROCESS | 4927 | 15:00:21 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39454/tests/kraken/driver.html?testName=imaging-desaturate) 15:00:21 INFO - PROCESS | 4927 | RSS: Main: 213794816 15:00:21 INFO - PROCESS | 4927 | 15:00:24 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39454/tests/kraken/driver.html?testName=json-parse-financial) 15:00:25 INFO - PROCESS | 4927 | RSS: Main: 212353024 15:00:25 INFO - PROCESS | 4927 | 15:00:26 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39454/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:00:27 INFO - PROCESS | 4927 | RSS: Main: 175046656 15:00:27 INFO - PROCESS | 4927 | 15:00:29 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39454/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:00:29 INFO - PROCESS | 4927 | RSS: Main: 182108160 15:00:29 INFO - PROCESS | 4927 | 15:00:31 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39454/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:00:32 INFO - PROCESS | 4927 | RSS: Main: 190287872 15:00:32 INFO - PROCESS | 4927 | 15:00:35 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39454/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:00:35 INFO - PROCESS | 4927 | RSS: Main: 199327744 15:00:35 INFO - PROCESS | 4927 | 15:00:38 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39454/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:00:39 INFO - PROCESS | 4927 | RSS: Main: 197070848 15:00:39 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:39454/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39454/tests/kraken/driver.html?testName=ai-astar) 15:00:41 INFO - PROCESS | 4927 | RSS: Main: 248250368 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | __start_tp_report 15:00:41 INFO - PROCESS | 4927 | _x_x_mozilla_page_load 15:00:41 INFO - PROCESS | 4927 | _x_x_mozilla_page_load_details 15:00:41 INFO - PROCESS | 4927 | |i|pagename|runs| 15:00:41 INFO - PROCESS | 4927 | |0;ai-astar;105;113;111;112;113;112;111;113;112;111 15:00:41 INFO - PROCESS | 4927 | |1;audio-beat-detection;150;122;126;168;123;121;122;119;148;121 15:00:41 INFO - PROCESS | 4927 | |2;audio-dft;163;160;159;163;160;159;161;161;161;161 15:00:41 INFO - PROCESS | 4927 | |3;audio-fft;75;76;75;109;77;78;78;75;92;79 15:00:41 INFO - PROCESS | 4927 | |4;audio-oscillator;82;82;106;83;83;83;95;83;84;110 15:00:41 INFO - PROCESS | 4927 | |5;imaging-gaussian-blur;113;113;113;113;113;113;114;113;113;113 15:00:41 INFO - PROCESS | 4927 | |6;imaging-darkroom;147;147;147;147;148;148;149;149;148;148 15:00:41 INFO - PROCESS | 4927 | |7;imaging-desaturate;96;96;97;96;96;97;96;96;95;95 15:00:41 INFO - PROCESS | 4927 | |8;json-parse-financial;55;63;55;54;57;57;56;56;58;56 15:00:41 INFO - PROCESS | 4927 | |9;json-stringify-tinderbox;64;68;64;65;65;65;64;65;64;64 15:00:41 INFO - PROCESS | 4927 | |10;stanford-crypto-aes;84;84;85;84;84;85;91;84;83;83 15:00:41 INFO - PROCESS | 4927 | |11;stanford-crypto-ccm;147;130;130;136;140;139;128;132;173;154 15:00:41 INFO - PROCESS | 4927 | |12;stanford-crypto-pbkdf2;179;178;173;175;171;175;171;170;170;172 15:00:41 INFO - PROCESS | 4927 | |13;stanford-crypto-sha256-iterative;66;66;69;73;63;64;64;63;69;64 15:00:41 INFO - PROCESS | 4927 | __end_tp_report 15:00:41 INFO - PROCESS | 4927 | __start_cc_report 15:00:41 INFO - PROCESS | 4927 | _x_x_mozilla_cycle_collect,1729 15:00:41 INFO - PROCESS | 4927 | __end_cc_report 15:00:41 INFO - PROCESS | 4927 | __startTimestamp1487372441248__endTimestamp 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | ------- Summary: start ------- 15:00:41 INFO - PROCESS | 4927 | Number of tests: 14 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.36 (2.1%) stddev-sans-first:0.87 15:00:41 INFO - PROCESS | 4927 | Values: 105.0 113.0 111.0 112.0 113.0 112.0 111.0 113.0 112.0 111.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:124.50 stddev:17.01 (13.7%) stddev-sans-first:16.75 15:00:41 INFO - PROCESS | 4927 | Values: 150.0 122.0 126.0 168.0 123.0 121.0 122.0 119.0 148.0 121.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.40 (0.9%) stddev-sans-first:1.24 15:00:41 INFO - PROCESS | 4927 | Values: 163.0 160.0 159.0 163.0 160.0 159.0 161.0 161.0 161.0 161.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#3] audio-fft Cycles:10 Average:81.40 Median:77.50 stddev:10.93 (14.1%) stddev-sans-first:11.34 15:00:41 INFO - PROCESS | 4927 | Values: 75.0 76.0 75.0 109.0 77.0 78.0 78.0 75.0 92.0 79.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:83.00 stddev:10.71 (12.9%) stddev-sans-first:11.05 15:00:41 INFO - PROCESS | 4927 | Values: 82.0 82.0 106.0 83.0 83.0 83.0 95.0 83.0 84.0 110.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:00:41 INFO - PROCESS | 4927 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 114.0 113.0 113.0 113.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 15:00:41 INFO - PROCESS | 4927 | Values: 147.0 147.0 147.0 147.0 148.0 148.0 149.0 149.0 148.0 148.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 15:00:41 INFO - PROCESS | 4927 | Values: 96.0 96.0 97.0 96.0 96.0 97.0 96.0 96.0 95.0 95.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:56.50 stddev:2.50 (4.4%) stddev-sans-first:2.57 15:00:41 INFO - PROCESS | 4927 | Values: 55.0 63.0 55.0 54.0 57.0 57.0 56.0 56.0 58.0 56.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:1.23 (1.9%) stddev-sans-first:1.27 15:00:41 INFO - PROCESS | 4927 | Values: 64.0 68.0 64.0 65.0 65.0 65.0 64.0 65.0 64.0 64.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.00 stddev:2.31 (2.8%) stddev-sans-first:2.44 15:00:41 INFO - PROCESS | 4927 | Values: 84.0 84.0 85.0 84.0 84.0 85.0 91.0 84.0 83.0 83.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#11] stanford-crypto-ccm Cycles:10 Average:140.90 Median:139.50 stddev:13.95 (10.0%) stddev-sans-first:14.62 15:00:41 INFO - PROCESS | 4927 | Values: 147.0 130.0 130.0 136.0 140.0 139.0 128.0 132.0 173.0 154.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:174.00 stddev:3.24 (1.9%) stddev-sans-first:2.73 15:00:41 INFO - PROCESS | 4927 | Values: 179.0 178.0 173.0 175.0 171.0 175.0 171.0 170.0 170.0 172.0 15:00:41 INFO - PROCESS | 4927 | 15:00:41 INFO - PROCESS | 4927 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:3.28 (5.0%) stddev-sans-first:3.48 15:00:41 INFO - PROCESS | 4927 | Values: 66.0 66.0 69.0 73.0 63.0 64.0 64.0 63.0 69.0 64.0 15:00:41 INFO - PROCESS | 4927 | -------- Summary: end -------- 15:00:41 INFO - PROCESS | 4927 | 15:00:42 INFO - TEST-INFO | 4927: exit 0 15:00:42 INFO - TEST-OK | kraken | took 61414ms 15:00:42 INFO - SUITE-END | took 1178s 15:00:42 INFO - Completed test suite (00:19:38) 15:00:42 INFO - javascript benchmark 15:00:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7310.682069925529, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3135.905922822315, 3141.7486601071914, 3148.9212769680753, 3151.43274210642, 3209.181658677037, 5106.821261255856, 5203.9219411708855, 5209.270702101709, 5251.317328747262, 5265.0, 5180.1970694542315, 5189.792408303672, 5233.162693968966, 5267.973660131704, 5297.12597421425, 4604.378408914796, 4616.099860527199, 4735.076660051292, 4799.760011999386, 4838.540338667838, 4821.54195351441, 4828.782704778276, 4865.6594038417325, 4876.000419913909, 4896.902061958759, 4618.307253911923, 4625.537446255381, 4645.349651048822, 4693.859184224479, 4844.975775121137, 4659.161350956826, 4687.624990000826, 4702.412198475191, 4704.435467743859, 4711.151992641323, 4689.507601701806, 4719.457262414832, 4750.45369782476, 4768.117898188846, 4769.451513075971, 5231.764570594332, 5238.240455134022, 5253.658512196695, 5277.9472205278225, 5277.973610131963, 5648.858778530528, 5653.886922261573, 5654.180143879114, 5657.9434205658235, 5698.487136157724, 5499.50504454601, 5559.8888022239325, 5566.944330556724, 5567.359753628303, 5644.0, 5189.766460509286, 5252.763625636855, 5262.500062494095, 5263.499967503081, 5263.789448422059, 5725.141228815711, 5761.0, 5764.827055188351, 5826.621269617462, 5827.650340979555, 5448.073827449343, 5454.263674403984, 5467.808626698046, 5534.7509362078, 5535.806246781343, 5626.718664066781, 5629.605927585111, 5633.295838020247, 5637.041702910515, 5639.125935480007, 5577.135543990688, 5633.492985631313, 5635.0, 5692.174634677948, 5709.286339207599, 5661.235733175969, 5670.234518339971, 5671.6597004179885, 5676.262085928885, 5717.371089180187, 4272.957270427318, 4305.935410968838, 4310.849120280838, 4357.215701173819, 4413.249747542925, 3881.7670939743707, 3915.7454765440157, 3937.665298449607, 3942.152437225953, 3942.3692209246383, 5258.868528286747, 5259.710715910662, 5260.13207820713, 5262.0, 5266.262723218752, 5566.777328906879, 5642.943570564242, 5654.886902261932, 5656.830295091154, 5681.801136960186, 5296.735163241822, 5301.0723123453245, 5332.813351532755, 5333.893322133536, 5347.545458636059, 4804.5195480451675, 4810.374651295309, 4823.131836269435, 4825.227963525819, 4867.902641947141, 5665.915011274917, 5699.487046165865, 5805.303363596311, 5819.039858423313, 5820.82537523875, 5588.720563971867, 5597.3003374578175, 5600.27196464457, 5600.663960162403, 5602.803901863415, 5665.951798917214, 5749.108888122348, 5776.393478684678, 5815.360310365941, 5815.76736930518, 5578.135389014709, 5619.8876022480135, 5629.4652008059165, 5631.183478395692, 5631.380548139783, 5720.856978575569, 5726.627769194989, 5729.28383952006, 5729.541636668973, 5731.684757338387, 5388.919166212589, 5431.918521222264, 5573.5819813513335, 5576.498115169492, 5582.385937546947, 5451.182322651475, 5485.451454854483, 5499.1476321170285, 5500.4499550044675, 5503.862403439946, 5479.862853487223, 5492.5605951524585, 5538.280023596906, 5560.944390556205, 5584.553235741212, 5564.4991950724625, 5568.832935011957, 5570.944290557205, 5576.721163941867, 5584.664920104807, 5274.393444753902, 5319.601029922694, 5320.521153096085, 5395.568354531707, 5465.808696695596], "value": 5189.255649765932, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.5653563001646, 574.3653263144228, 586.4428792646986, 586.6919867069788, 594.2720167794453, 31977.0, 32476.512852307234, 32520.1869953251, 43512.56487435125, 44845.77577112118, 44693.32960005602, 45217.095658086815, 45249.321260181125, 45311.77344113283, 45351.0, 9081.318901082428, 9278.675246366378, 9281.535923203846, 9281.907180928205, 9288.256939444862, 7438.144613369478, 7629.8474030519355, 9130.954345228265, 10728.0, 10980.890191098066, 10468.267221294513, 10523.0, 10526.684199474028, 10528.105111065568, 10531.526081326321, 10974.122070234402, 11026.117910567176, 11034.448277586089, 11050.668479945614, 11050.889491105067, 10718.410487423189, 10748.140148788156, 10748.73128171794, 10757.139428845674, 10763.515641796137, 42329.78835105805, 42674.57325426737, 42689.0, 42850.14299713988, 42891.142177156595, 3743.794091325003, 3746.587875333711, 3771.9811400943095, 3808.923821523582, 3819.388897776342, 31796.682033179604, 31847.840760796047, 31936.20159496008, 32349.676503234903, 32350.35299294001, 3840.9615903841163, 3952.150287688161, 3970.7419017763755, 4081.2245673322273, 4134.400511925784, 292591.0, 293592.0, 294005.0, 294105.0, 294162.0, 55431.722841385534, 55471.722641386536, 55792.442075579536, 55792.999999999185, 55811.116832217325, 59405.40594594085, 59566.0, 59638.0, 59642.70178649079, 59658.0, 14936.626584335261, 15118.546443606709, 15151.696966060617, 15165.545033649008, 15199.468018619295, 11362.54549817998, 11365.829512557319, 11377.544898204145, 11431.59989400367, 11450.19848610606, 13203.40584673692, 13762.518311859038, 16702.349980807903, 20799.480012999495, 20872.165113395295, 7140.0, 7469.58917259556, 7521.623918804038, 7626.682393158993, 7812.726554570563, 810.1857633078719, 846.1073567386344, 847.737201467547, 853.7140058080613, 854.2567965869733, 29620.0, 30759.0, 31027.379452410827, 31162.68837311598, 31181.68818311833, 1006.2603986070233, 1082.9025387714985, 1091.770728147082, 1104.823228283487, 1109.2789686703707, 15658.530244092695, 16074.35702571884, 16183.514494564946, 16246.431374901822, 16509.257083431035, 481229.0, 489157.0, 489910.0, 490024.0, 490425.0, 59562.10656840151, 60232.39767602355, 60253.69873150694, 60714.696426518465, 60813.69593152006, 1346.4277681985313, 1375.6354566040006, 1380.3098450774814, 1385.49429458247, 1394.9721005580034, 29236.0, 30513.23716907095, 30524.0, 30541.847290763402, 31580.368392632477, 51211.487885122166, 51800.222996654324, 51806.74096629493, 51897.48102519078, 51973.22040169325, 302516.0, 302571.89560453716, 302674.0, 303079.0, 303643.0, 31995.48049714483, 40172.0, 41421.0, 41453.0, 41742.0, 33918.491222632176, 44146.77926610346, 45214.0, 46612.54199328091, 48948.265776012675, 35463.468047978786, 37574.30332115382, 38261.23477530378, 39724.0, 40742.1851562973, 38395.616043840324, 39207.6079239204, 39341.803290984506, 40844.59155408408, 41022.79488602538, 39123.0, 39733.40399893946, 39890.40164397479, 40021.39967900542, 40077.1984560313, 32178.19554511156, 34072.31855362929, 34307.65692343045, 34650.65349346474, 34790.13024674403, 26397.20808375752, 26490.602640960788, 26507.0, 26995.730042700106, 27150.189667494702], "value": 19675.75333854503, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.91474418255876, 379.3532027892444, 385.7376983651117, 390.23319177815586, 401.8252060353746, 18218.180181891814, 20133.697994530095, 20925.37223883282, 20987.265445709403, 21435.14259429623, 20331.0, 20657.17371305146, 20741.688874666892, 20766.169353225854, 20866.374008779723, 5814.4766970972605, 6024.909626355608, 7403.296686814744, 7649.349805266559, 7799.415043871704, 7226.56640601563, 7579.090509138911, 7742.574158421313, 7812.414068944823, 7841.6079196040255, 7753.635141281935, 7756.0692716874055, 7767.922320776805, 7775.455718099735, 7782.805429864242, 7572.583507907063, 7828.804279892991, 7883.792538089344, 7908.881366779503, 7911.2088791121005, 7058.505904586681, 7373.446991475661, 7434.888476672854, 7445.8883116753295, 7665.386769058462, 27315.590266146024, 27757.583636245472, 27812.0, 27835.860820695765, 28031.579526307123, 2761.1164427383246, 2802.7477527022666, 2827.604135421043, 2855.1434569629237, 2879.9424011519654, 15724.449644262397, 15974.281157347947, 16044.598885027852, 16253.431129910396, 17195.31218751261, 1984.2162345873298, 2009.779193990634, 2010.155734591475, 2025.1494372363495, 2028.8328746435675, 98907.50546247223, 104134.47932760364, 105211.47394263056, 105981.47009264982, 106040.0, 18393.54016149607, 18988.0, 19170.0, 19210.423687289403, 19333.523269704525, 35227.0, 35267.4709879352, 35321.29357412889, 35349.11627209289, 35526.64473355285, 9152.81694366109, 9589.664361747306, 9614.71155865325, 9631.855522167172, 9912.801743965225, 7726.150123486412, 7764.883526747103, 7992.400569957276, 8002.0, 8035.196480351918, 9248.398854074463, 9719.201947639709, 9899.505024748878, 10054.19566434698, 10136.493175341204, 3693.353663108945, 3704.5554533456157, 3763.7741735495956, 3823.9426408603895, 4026.516817981861, 843.1568431568431, 861.1130535548396, 876.706303388372, 883.7172104926491, 902.4540153207346, 19464.416067517996, 20228.190872365234, 20301.086451109735, 20317.390478285673, 20451.897740511202, 861.1904809479195, 874.6501399440275, 874.903760586335, 876.2639382918379, 878.3719640457103, 10431.0, 11024.283421577571, 11099.611513596985, 11170.776584468265, 11251.09991200702, 610350.0, 613507.0, 622229.0, 630291.0, 651176.0, 103148.48425757824, 105583.0, 109018.45490772495, 110304.0, 111579.0, 1035.53400969564, 1057.1278695076592, 1101.823708206683, 1101.9173561982884, 1108.4924502676192, 17226.655466890843, 17460.0, 17922.283108675507, 17998.0, 18066.458006259832, 38444.61555384522, 39852.40221396739, 40533.25868644752, 41983.0, 43962.12075758531, 60627.393726063936, 60792.696036519534, 61042.0, 62738.686306568175, 63373.0, 8886.777830554029, 8945.23965462917, 9065.954670226607, 9103.954480227556, 9252.761533463234, 8215.0, 13619.387127579084, 13693.109947853358, 14218.0, 14233.430662773373, 11599.246049006788, 12266.570670026505, 12356.505739770308, 12408.131430799758, 12746.490140394651, 11662.0, 12107.394630268274, 12478.5008599655, 12525.185862918881, 12625.558105466265, 12096.879031209575, 12217.0226381891, 12656.43046062911, 12774.936125319315, 13100.148490348098, 8447.113053129333, 8616.439931404439, 8629.87055194185, 8830.792301924519, 9358.0, 10392.37645741258, 12378.81431778506, 12398.81401778956, 12486.937565312115, 12594.992400608113], "value": 10939.120192759276, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1234.9135560510767, 1404.9297535123249, 1407.472197925778, 1414.5402744108164, 1419.1414194412382, 6949.930500694992, 8116.472429292099, 8348.582570871462, 8350.498970061786, 8542.957285213566, 8373.623186956587, 8413.0, 8437.873431898526, 8440.155984401557, 8440.409171358007, 1926.3257859749046, 2249.7862703043224, 2277.146070223666, 2277.4761804784825, 2279.2136712834044, 1994.700794880765, 2012.8490363222743, 2014.4460273424852, 2017.072146812468, 2170.869747815136, 2232.274510784002, 2247.0, 2248.2468373094953, 2249.921252756154, 2254.0195015168456, 2079.189116244666, 2102.7581828089737, 2105.52625659227, 2112.6936594193908, 2117.9258725944674, 2144.785521447858, 2210.2264207527396, 2230.6877037214804, 2231.7433495147943, 2235.675827005075, 8349.165083491664, 8385.748427547183, 8405.873911891327, 8409.873851892227, 8429.98840139181, 5106.131957567222, 5251.396089449724, 5255.474452554752, 5259.894802103937, 5268.236105764681, 3199.216192032967, 3258.0714496368423, 3299.538064670948, 3306.338732253535, 3351.614564325085, 1287.0475847872508, 1295.1581472043154, 1316.433933408634, 1335.1855368225374, 1374.9450021999107, 11670.591529296518, 11772.058235341237, 11809.40952952349, 11816.291022538675, 11830.408479576074, 4331.393604895317, 4366.410534577855, 4371.540988196257, 4405.185040767468, 4486.573775491323, 9173.541322933826, 9177.311701622404, 9178.586963586655, 9194.310426718022, 9194.402363846328, 5189.818356357509, 5191.454897235812, 5194.428612852583, 5197.506236907488, 5202.583793296603, 4627.120847039052, 4767.61859051275, 4813.687110337887, 4832.178529649968, 4891.119598472309, 3241.918952026171, 3354.3459025490283, 3574.463830425405, 3600.765950213228, 3619.0, 1080.7568297133082, 1090.907272881814, 1100.6973082402403, 1101.3557069114497, 1112.5605385872698, 987.8666380038603, 988.6342053440274, 1006.1849901579744, 1008.7780688248573, 1036.549101141006, 5163.690178589297, 5484.040292948718, 5858.414158584107, 5944.5838791284195, 5970.373110823388, 987.8024395121005, 1035.0839507036328, 1050.4642632257603, 1061.9681409557725, 1073.5024640626282, 3877.515310586177, 4045.9190816183514, 4148.896277593024, 4163.958360416417, 4209.97895010523, 9953.950230248802, 10271.126954208827, 10277.17782577392, 10283.125934295518, 10289.125424339016, 10164.085232329126, 10212.540435680412, 10212.948935255276, 10218.591256349811, 10273.486325683685, 7581.696732130763, 7859.724909628136, 7988.720394786154, 8064.677412903536, 8108.797280068045, 5890.52875769946, 5912.0, 5974.312954010345, 6060.848478787889, 6132.601380910227, 9174.08259174104, 9193.172614464731, 9216.2626989842, 9242.306826988137, 9336.906630933603, 9096.090390960851, 9153.908460915305, 9250.4912229828, 9427.952860235655, 9429.0, 7524.05949256343, 7644.464887457824, 7799.844003120019, 7817.140114587504, 7837.255460731221, 7802.960985195038, 7809.96095019544, 7852.646630901738, 7877.803054923673, 7973.322267607318, 7131.393831524379, 7153.2131465537705, 7165.964170179116, 7224.0, 7337.119545654556, 6844.691988860613, 7021.754238601625, 7034.366906978396, 7308.196098429275, 7368.410527157922, 6315.242170939517, 6424.51816113784, 6477.514186435942, 6479.902801457888, 6481.35186481367, 5260.89478210426, 5279.841604751864, 5279.920801188062, 5342.732863356739, 5419.756110975094, 5379.0, 5408.405075441777, 5436.836894893159, 5457.471907865804, 5576.665400076008], "value": 4454.092167316158, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.44319741985737, 373.8691457989704, 375.539963544658, 383.58572741439247, 383.64896120050156, 5100.642954993143, 5155.845324640262, 5182.144946083897, 5185.999999999995, 5191.844244672657, 5161.483851614837, 5187.740612969355, 5210.8697282567955, 5253.947460525394, 5261.500157485042, 3991.8602848900296, 4115.773632450214, 4126.628603425691, 4140.979295103528, 4151.460310159675, 4104.8563300284495, 4110.6300432961025, 4113.321301985184, 4118.38224266361, 4125.566815484376, 4114.9630932688215, 4125.731827431222, 4129.834806607747, 4132.8966775830695, 4138.13099249158, 3945.506811648544, 4078.938815917764, 4156.5635608261155, 4156.625903668684, 4162.958370416287, 3659.5242618459697, 3706.2031663192274, 3706.592274849764, 3712.7401081924277, 3720.553533575961, 5015.172496538067, 5096.464871188546, 5108.872278193075, 5115.437301896788, 5117.616178786606, 4560.065186636732, 4807.831725889577, 4809.278608208797, 4817.807287708522, 4819.759012049419, 5501.697406642633, 5512.283403157564, 5520.171974203861, 5523.337199536041, 5535.861603459905, 4126.741343890112, 4132.690048246393, 4142.420061191436, 4143.047099167178, 4144.067584793397, 4976.87557811054, 5389.946100539023, 5719.3136823581435, 5753.769849206027, 5764.607687809554, 4116.279651061052, 4219.620234178878, 4223.761406454584, 4224.176285624335, 4236.131593023423, 4270.679699022586, 4272.632757517565, 4276.743395396224, 4286.185624731291, 4287.228298906227, 4767.284907263869, 4772.308015337826, 4779.498152693986, 4785.736784476887, 4794.664373493892, 5416.729163541807, 5432.809851655173, 5468.179773034077, 5468.589855760833, 5506.182959623315, 1406.8522805105317, 1409.365785396555, 1418.7729963206043, 1419.552840855148, 1425.4583258361756, 973.6981535723949, 1019.169376957777, 1032.7108409645311, 1041.9374837509774, 1051.574112484445, 5158.38099428071, 5161.071007218661, 5165.199394093921, 5170.1986192140275, 5177.611679124006, 5496.674511920311, 5508.944910550923, 5513.58648101386, 5515.227868098469, 5525.557955363562, 4900.338454308623, 5024.015513872, 5087.287779710843, 5092.567131793765, 5102.33669622954, 2856.2573730829718, 2868.6987866274158, 2878.8992385266833, 2887.1771545109964, 2893.2766808297924, 5616.6068375213345, 5637.267155269788, 5639.43605639441, 5639.746211420496, 5646.6894320811925, 5230.947690523046, 5466.808661696821, 5506.614536982453, 5538.889222215614, 5574.052411090124, 5616.466435688603, 5656.830295091154, 5700.543956483471, 5764.510016648632, 5785.0, 5580.748866300945, 5587.720613969366, 5589.608727389043, 5599.832005039855, 5607.607467477237, 5364.0, 5364.30264065669, 5485.232067510631, 5504.339479262514, 5542.8337149885565, 5711.914321285267, 5725.19847221381, 5742.530711793968, 5755.453231942959, 5789.189513468202, 4158.9584104158575, 4185.476815398075, 4198.244316023023, 4210.30529962565, 4215.3044747616295, 5284.709340986283, 5549.3074612242035, 5597.720113994365, 5634.408387119293, 5654.915176272277, 5461.262729531462, 5486.780528778964, 5508.614396992172, 5518.42056584069, 5546.722663866871, 5238.371395432572, 5413.296271484682, 5453.263809385682, 5479.369872464717, 5506.944930550643, 5486.653141583551, 5493.708978390155, 5495.697736624524, 5505.834824955257, 5509.338879334506, 5566.109422492382, 5575.303087114111, 5578.843761397785, 5599.496045355938, 5614.438556144353, 5022.698638081727, 5344.0, 5361.651492652965, 5448.237246785532, 5468.726563671721], "value": 4219.764762057739, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.6127752291284, 744.0476190476193, 772.8454309138174, 819.1644522586962, 821.3224090125648, 15066.623334416632, 15185.316660750264, 15861.20693965303, 15882.126483043428, 16141.596460088489, 16081.356745730185, 16663.50009499714, 16681.416150434736, 16845.578860528494, 16900.0, 771.8031901865016, 784.7920301120193, 803.5901690138043, 805.9838803223932, 806.4717609965483, 796.9997652945545, 815.9347252219808, 816.481534225768, 816.8978877640295, 816.9264766171043, 851.0340763233743, 859.3340161374922, 861.2765277167179, 861.6208868098048, 861.7716305179133, 849.9970035358275, 852.2244757270915, 853.2747164909808, 853.3727710133047, 853.4324674091722, 624.1199908129561, 624.9906251406233, 625.0405627361978, 625.5433533520555, 626.4737620398841, 15850.682986340209, 15920.044797312199, 16485.258163382678, 16538.83461165385, 16691.165441727866, 15369.154696491689, 15620.531384058497, 15844.28700708471, 15993.52019439407, 16111.27499262536, 11610.24533405326, 11639.883601163965, 11699.88300117005, 11759.882401175964, 12577.11960162789, 3640.199156185635, 3721.60923103073, 3736.4955730976512, 3818.217265460574, 3844.942325865114, 77233.0, 78146.21853781446, 78753.60623196905, 79603.60198198972, 79879.6006019972, 11548.480318385693, 11555.083104006597, 11619.128565357632, 11871.228370155912, 11964.0, 23793.405164871016, 23838.880805596207, 24044.15845445436, 24223.456537063354, 24241.878790606286, 7620.847583048308, 7622.733204337822, 7652.234776522303, 7653.081630204411, 7998.760037198894, 4206.28493156158, 4230.175115852441, 4232.746035237896, 4306.978465107654, 4393.384926110347, 3491.2319289756215, 3527.470879368064, 3533.3463309287863, 3561.3767590671528, 3578.3380074686265, 556.9303837020373, 562.7748900439857, 567.324883388766, 574.7930744931821, 632.4718859752066, 712.7291629180982, 718.4109030594863, 740.0188962044326, 748.4274529984566, 755.2560727683227, 10420.0, 10573.682789516326, 10674.519646615921, 10759.946200268949, 10841.620543280947, 727.1238158019573, 763.5075376382202, 765.3609236287651, 774.3882332957013, 779.3492433817712, 4566.9057630472425, 4929.035970652002, 4955.950440495549, 4957.429895561984, 4968.875778105504, 70882.2911770886, 71689.0, 72137.63931180311, 73357.6332118336, 74548.25451745413, 66469.0, 66477.0, 66921.0, 68125.93433295513, 69429.42526229164, 59769.40230597725, 60060.69969650124, 60610.39389606047, 61066.0, 61486.69256653778, 9551.665691700757, 9588.185004274435, 9614.9038509614, 9618.951905240428, 9638.277129215197, 39494.0, 40578.391324129574, 40826.387604185365, 41508.169836602494, 41951.7902410486, 43821.561784381745, 45827.08345833131, 47273.527264726916, 47379.0, 50451.49548504615, 12431.502739890304, 12434.440450179583, 12683.556075537312, 13117.737645247233, 13993.020488565702, 14815.629609259855, 15171.620709482351, 15225.619359515658, 15422.46021389246, 16004.519864404087, 13572.1856688599, 13863.168209907439, 14147.64630884236, 14760.0, 14837.703245935238, 13749.312534373441, 14037.368318425491, 14248.145111293356, 14266.785998210244, 14443.711125777216, 13911.373988170355, 14074.929625351808, 14077.859221407654, 14171.787423188867, 14636.195009274166, 10306.072453479223, 10615.681529554126, 10615.787684246427, 10780.460976951277, 10917.945410272898, 9867.506624668595, 10011.499425028574, 10516.842247366143, 11074.446277685922, 11309.179418630261], "value": 7271.632922616846, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 111.0, 112.0, 113.0, 112.0, 111.0, 113.0, 112.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 122.0, 126.0, 168.0, 123.0, 121.0, 122.0, 119.0, 148.0, 121.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 159.0, 163.0, 160.0, 159.0, 161.0, 161.0, 161.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 109.0, 77.0, 78.0, 78.0, 75.0, 92.0, 79.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 106.0, 83.0, 83.0, 83.0, 95.0, 83.0, 84.0, 110.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 148.0, 148.0, 149.0, 149.0, 148.0, 148.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 95.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 63.0, 55.0, 54.0, 57.0, 57.0, 56.0, 56.0, 58.0, 56.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 64.0, 65.0, 65.0, 65.0, 64.0, 65.0, 64.0, 64.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 84.0, 85.0, 91.0, 84.0, 83.0, 83.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 130.0, 130.0, 136.0, 140.0, 139.0, 128.0, 132.0, 173.0, 154.0], "value": 140.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 178.0, 173.0, 175.0, 171.0, 175.0, 171.0, 170.0, 170.0, 172.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 69.0, 73.0, 63.0, 64.0, 64.0, 63.0, 69.0, 64.0], "value": 66.1, "unit": "score"}]}]} 15:00:42 INFO - Return code: 0 15:00:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:00:42 ERROR - # TBPL SUCCESS # 15:00:42 INFO - Running post-action listener: _resource_record_post_action 15:00:42 INFO - [mozharness: 2017-02-17 23:00:42.546440Z] Finished run-tests step (success) 15:00:42 INFO - Running post-run listener: _resource_record_post_run 15:00:42 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 3588096; Write bytes: 363954176; Read time: 220; Write time: 221836 15:00:42 INFO - TinderboxPrint: CPU usage
13.1% 15:00:42 INFO - TinderboxPrint: I/O read bytes / time
3,588,096 / 220 15:00:42 INFO - TinderboxPrint: I/O write bytes / time
363,954,176 / 221,836 15:00:42 INFO - TinderboxPrint: CPU idle
8,285.1 (86.9%) 15:00:42 INFO - TinderboxPrint: CPU user
1,204.1 (12.6%) 15:00:42 INFO - TinderboxPrint: Swap in / out
0 / 0 15:00:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110768128; Read time: 0; Write time: 94720 15:00:42 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 3588096; Write bytes: 253186048; Read time: 220; Write time: 127116 15:00:43 INFO - Running post-run listener: _upload_blobber_files 15:00:43 INFO - Blob upload gear active. 15:00:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:00:43 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 15:00:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:00:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:00:43 INFO - (blobuploader) - INFO - Open directory for files ... 15:00:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:00:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:00:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:00:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:00:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:00:47 INFO - (blobuploader) - INFO - Done attempting. 15:00:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:00:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:00:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:00:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:00:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:00:48 INFO - (blobuploader) - INFO - Done attempting. 15:00:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:00:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:00:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:00:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:00:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:00:49 INFO - (blobuploader) - INFO - Done attempting. 15:00:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:00:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:00:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:00:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:00:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:00:50 INFO - (blobuploader) - INFO - Done attempting. 15:00:50 INFO - (blobuploader) - INFO - Iteration through files over. 15:00:50 INFO - Return code: 0 15:00:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:00:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:00:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d24852130fa83a1b6d4c5692090928befdbe3d57e41067f157110281f5cf965ac05639c7dae0d043788c2dc0234968c6f8ed8b6322b3217b6611bc039d574e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3412c96f0a691d6a1a691f06664a446f6436029f069c0d2d0a2b7875594a8d70e52ab55b34a1d275466e390e88d48ac1cbcfc2851abc8b9e132d84c05ffaa851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96e9d41766d048b1a726de32b8dcf64a04d1c84cce2ccd026caa117539451eea9d3f7c9652dcdadcf775afdd7f3f5ee39247a8b2b0bdacfb1cad209b4ff0b79a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee63b1248117c1bef854b8ae895723d7d64091729d62604b2b57666de9adeca6b215761e79633c1e4efa2dd2b1e8b7f79828a8f5cdca370cc5ec5ee84ef3b0b3"} 15:00:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:00:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:00:50 INFO - Contents: 15:00:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d24852130fa83a1b6d4c5692090928befdbe3d57e41067f157110281f5cf965ac05639c7dae0d043788c2dc0234968c6f8ed8b6322b3217b6611bc039d574e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3412c96f0a691d6a1a691f06664a446f6436029f069c0d2d0a2b7875594a8d70e52ab55b34a1d275466e390e88d48ac1cbcfc2851abc8b9e132d84c05ffaa851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96e9d41766d048b1a726de32b8dcf64a04d1c84cce2ccd026caa117539451eea9d3f7c9652dcdadcf775afdd7f3f5ee39247a8b2b0bdacfb1cad209b4ff0b79a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee63b1248117c1bef854b8ae895723d7d64091729d62604b2b57666de9adeca6b215761e79633c1e4efa2dd2b1e8b7f79828a8f5cdca370cc5ec5ee84ef3b0b3"} 15:00:50 INFO - Running post-run listener: copy_logs_to_upload_dir 15:00:50 INFO - Copying logs to upload dir... 15:00:50 INFO - mkdir: /builds/slave/test/build/upload/logs 15:00:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.800210 ========= master_lag: 0.33 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-02-17 15:00:50.510259) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 15:00:50.513830) ========= 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=8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d24852130fa83a1b6d4c5692090928befdbe3d57e41067f157110281f5cf965ac05639c7dae0d043788c2dc0234968c6f8ed8b6322b3217b6611bc039d574e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3412c96f0a691d6a1a691f06664a446f6436029f069c0d2d0a2b7875594a8d70e52ab55b34a1d275466e390e88d48ac1cbcfc2851abc8b9e132d84c05ffaa851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96e9d41766d048b1a726de32b8dcf64a04d1c84cce2ccd026caa117539451eea9d3f7c9652dcdadcf775afdd7f3f5ee39247a8b2b0bdacfb1cad209b4ff0b79a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee63b1248117c1bef854b8ae895723d7d64091729d62604b2b57666de9adeca6b215761e79633c1e4efa2dd2b1e8b7f79828a8f5cdca370cc5ec5ee84ef3b0b3"} build_url:https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011259 build_url: 'https://queue.taskcluster.net/v1/task/Czym9kfRT02cFUT4KThoJw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d24852130fa83a1b6d4c5692090928befdbe3d57e41067f157110281f5cf965ac05639c7dae0d043788c2dc0234968c6f8ed8b6322b3217b6611bc039d574e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3412c96f0a691d6a1a691f06664a446f6436029f069c0d2d0a2b7875594a8d70e52ab55b34a1d275466e390e88d48ac1cbcfc2851abc8b9e132d84c05ffaa851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96e9d41766d048b1a726de32b8dcf64a04d1c84cce2ccd026caa117539451eea9d3f7c9652dcdadcf775afdd7f3f5ee39247a8b2b0bdacfb1cad209b4ff0b79a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee63b1248117c1bef854b8ae895723d7d64091729d62604b2b57666de9adeca6b215761e79633c1e4efa2dd2b1e8b7f79828a8f5cdca370cc5ec5ee84ef3b0b3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 15:00:50.540749) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 15:00:50.541254) ========= 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=8887e36bb8e381bfd44124ac000001a8-1487371193.866236-1728595368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 15:00:50.624465) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 15:00:50.624785) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 15:00:50.625091) ========= ========= Total master_lag: 0.48 =========